builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1495579715.71 results: success (0) revision: 96e18bec9fc8a5ce623c16167c12756bbe190d73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.706257) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.706724) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.707017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.723282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.723593) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-23 15:48:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.3M=0s 2017-05-23 15:48:35 (49.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.187863 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.924910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.925220) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020106 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.977157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:35.980506) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 96e18bec9fc8a5ce623c16167c12756bbe190d73 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 96e18bec9fc8a5ce623c16167c12756bbe190d73 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-23 15:48:36,024 truncating revision to first 12 chars 2017-05-23 15:48:36,024 Setting DEBUG logging. 2017-05-23 15:48:36,024 attempt 1/10 2017-05-23 15:48:36,025 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/96e18bec9fc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 15:48:36,341 unpacking tar archive at: mozilla-central-96e18bec9fc8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.617856 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:36.613558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:36.613891) ========= script_repo_revision: 96e18bec9fc8a5ce623c16167c12756bbe190d73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:36.614253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:36.614526) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 15:48:36.623138) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-05-23 15:48:36.623463) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:48:36 INFO - MultiFileLogger online at 20170523 15:48:36 in /builds/slave/test 15:48:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:48:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:36 INFO - {'append_to_log': False, 15:48:36 INFO - 'base_work_dir': '/builds/slave/test', 15:48:36 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:48:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:36 INFO - 'branch': 'Firefox-Non-PGO', 15:48:36 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:36 INFO - 'config_files': ('talos/linux_config.py',), 15:48:36 INFO - 'default_actions': ('clobber', 15:48:36 INFO - 'read-buildbot-config', 15:48:36 INFO - 'download-and-extract', 15:48:36 INFO - 'populate-webroot', 15:48:36 INFO - 'create-virtualenv', 15:48:36 INFO - 'install', 15:48:36 INFO - 'run-tests'), 15:48:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:36 INFO - 'download_minidump_stackwalk': True, 15:48:36 INFO - 'download_symbols': 'ondemand', 15:48:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:48:36 INFO - 'tooltool.py': '/tools/tooltool.py', 15:48:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:48:36 INFO - '/tools/misc-python/virtualenv.py')}, 15:48:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:36 INFO - 'gecko_profile': False, 15:48:36 INFO - 'gecko_profile_interval': 0, 15:48:36 INFO - 'installer_path': 'installer.exe', 15:48:36 INFO - 'log_level': 'info', 15:48:36 INFO - 'log_name': 'talos', 15:48:36 INFO - 'log_to_console': True, 15:48:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:48:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:48:36 INFO - 'opt_config_files': (), 15:48:36 INFO - 'pip_index': False, 15:48:36 INFO - 'suite': 'dromaeojs-e10s', 15:48:36 INFO - 'system_bits': '32', 15:48:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:36 INFO - 'title': 'talos-linux64-ix-039', 15:48:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:36 INFO - 'use_talos_json': True, 15:48:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:36 INFO - 'work_dir': 'build'} 15:48:36 INFO - [mozharness: 2017-05-23 22:48:36.865519Z] Running clobber step. 15:48:36 INFO - Running pre-action listener: _resource_record_pre_action 15:48:36 INFO - Running main action method: clobber 15:48:36 INFO - rmtree: /builds/slave/test/build 15:48:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:37 INFO - Running post-action listener: _resource_record_post_action 15:48:37 INFO - [mozharness: 2017-05-23 22:48:37.851318Z] Finished clobber step (success) 15:48:37 INFO - [mozharness: 2017-05-23 22:48:37.851419Z] Running read-buildbot-config step. 15:48:37 INFO - Running pre-action listener: _resource_record_pre_action 15:48:37 INFO - Running main action method: read_buildbot_config 15:48:37 INFO - Using buildbot properties: 15:48:37 INFO - { 15:48:37 INFO - "project": "", 15:48:37 INFO - "product": "firefox", 15:48:37 INFO - "who": "ryanvm@gmail.com", 15:48:37 INFO - "installer_path": "public/build/target.tar.bz2", 15:48:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:48:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:48:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:48:37 INFO - "stage_platform": "linux64", 15:48:37 INFO - "basedir": "/builds/slave/test", 15:48:37 INFO - "buildnumber": 626, 15:48:37 INFO - "platform": "ubuntu64_hw", 15:48:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:48:37 INFO - "slavebuilddir": "test", 15:48:37 INFO - "taskId": "cCIYAX8RRlqAEmX3vcf2Fw", 15:48:37 INFO - "branch": "mozilla-central", 15:48:37 INFO - "script_repo_revision": "production", 15:48:37 INFO - "revision": "96e18bec9fc8a5ce623c16167c12756bbe190d73", 15:48:37 INFO - "slavename": "talos-linux64-ix-039", 15:48:37 INFO - "repo_path": "mozilla-central" 15:48:37 INFO - } 15:48:37 INFO - Finding installer, test and symbols from parent task. 15:48:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCIYAX8RRlqAEmX3vcf2Fw'}, attempt #1 15:48:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCIYAX8RRlqAEmX3vcf2Fw'}, attempt #1 15:48:38 INFO - Task dependencies: S8aEo-RdQuG3FXr-RuPqQg 15:48:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg'}, attempt #1 15:48:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 15:48:39 INFO - Set test_url: https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/test_packages.json 15:48:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.crashreporter-symbols.zip 15:48:39 INFO - Running post-action listener: _resource_record_post_action 15:48:39 INFO - [mozharness: 2017-05-23 22:48:39.222434Z] Finished read-buildbot-config step (success) 15:48:39 INFO - [mozharness: 2017-05-23 22:48:39.222654Z] Running download-and-extract step. 15:48:39 INFO - Running pre-action listener: _resource_record_pre_action 15:48:39 INFO - Running main action method: download_and_extract 15:48:39 INFO - mkdir: /builds/slave/test/build/tests 15:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:39 INFO - trying https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.test_packages.json 15:48:39 INFO - Downloading https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:48:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:48:39 INFO - Downloaded 986 bytes. 15:48:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:48:39 INFO - Using the following test package requirements: 15:48:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:48:39 INFO - u'common': [u'target.common.tests.zip'], 15:48:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:48:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:48:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:48:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:48:39 INFO - u'mozbase': [u'target.common.tests.zip'], 15:48:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:48:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:48:39 INFO - u'web-platform': [u'target.common.tests.zip', 15:48:39 INFO - u'target.web-platform.tests.zip'], 15:48:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:48:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:48:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip 15:48:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:48:39 INFO - Fetch https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip into memory 15:48:41 INFO - Content-Length response header: 63523681 15:48:41 INFO - Bytes received: 63523681 15:48:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip 15:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.common.tests.zip into memory 15:48:53 INFO - Content-Length response header: 63523681 15:48:53 INFO - Bytes received: 63523681 15:49:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.talos.tests.zip 15:49:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:49:05 INFO - Fetch https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.talos.tests.zip into memory 15:49:05 INFO - Content-Length response header: 14411859 15:49:05 INFO - Bytes received: 14411859 15:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:06 INFO - trying https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 15:49:06 INFO - Downloading https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:49:07 INFO - Downloaded 54912871 bytes. 15:49:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 15:49:07 INFO - mkdir: /builds/slave/test/properties 15:49:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:07 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:07 INFO - Contents: 15:49:07 INFO - build_url:https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 15:49:07 INFO - Running post-action listener: _resource_record_post_action 15:49:07 INFO - Running post-action listener: set_extra_try_arguments 15:49:07 INFO - [mozharness: 2017-05-23 22:49:07.227983Z] Finished download-and-extract step (success) 15:49:07 INFO - [mozharness: 2017-05-23 22:49:07.228082Z] Running populate-webroot step. 15:49:07 INFO - Running pre-action listener: _resource_record_pre_action 15:49:07 INFO - Running main action method: populate_webroot 15:49:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:49:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:49:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:49:07 INFO - '--tppagecycles', 15:49:07 INFO - '7'], 15:49:07 INFO - 'tests': ['tsvgm']}}, 15:49:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:49:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:49:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['damp', 'tps']}, 15:49:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'tests': ['damp', 'tps']}, 15:49:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['dromaeo_dom']}, 15:49:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:49:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:49:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:49:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['a11yr', 15:49:07 INFO - 'ts_paint', 15:49:07 INFO - 'tpaint', 15:49:07 INFO - 'sessionrestore', 15:49:07 INFO - 'sessionrestore_no_auto_restore', 15:49:07 INFO - 'tabpaint']}, 15:49:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:49:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:49:07 INFO - 'bloom_basic_ref']}, 15:49:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['tsvgx', 15:49:07 INFO - 'tsvgr_opacity', 15:49:07 INFO - 'tart', 15:49:07 INFO - 'tscrollx', 15:49:07 INFO - 'cart', 15:49:07 INFO - 'tsvg_static']}, 15:49:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:07 INFO - 'tsvgr_opacity', 15:49:07 INFO - 'tart', 15:49:07 INFO - 'tscrollx', 15:49:07 INFO - 'cart', 15:49:07 INFO - 'tsvg_static']}, 15:49:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'talos_options': ['--disable-e10s'], 15:49:07 INFO - 'tests': ['tp5o']}, 15:49:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:49:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'talos_options': ['--disable-e10s', 15:49:07 INFO - '--xperf_path', 15:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:07 INFO - 'tests': ['tp5n']}, 15:49:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:49:07 INFO - 'talos_options': ['--xperf_path', 15:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:07 INFO - 'tests': ['tp5n']}}, 15:49:07 INFO - 'talos.zip': {'path': '', 15:49:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:49:07 INFO - Running post-action listener: _resource_record_post_action 15:49:07 INFO - [mozharness: 2017-05-23 22:49:07.242051Z] Finished populate-webroot step (success) 15:49:07 INFO - [mozharness: 2017-05-23 22:49:07.242140Z] Running create-virtualenv step. 15:49:07 INFO - Running pre-action listener: _resource_record_pre_action 15:49:07 INFO - Running main action method: create_virtualenv 15:49:07 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:49:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:49:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:07 INFO - Using real prefix '/usr' 15:49:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:07 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:09 INFO - Installing pip.................done. 15:49:09 INFO - Return code: 0 15:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:49:11 INFO - Reading from file tmpfile_stdout 15:49:11 INFO - Output received: 15:49:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:49:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:49:11 INFO - Using env: {'DISPLAY': ':0', 15:49:11 INFO - 'HOME': '/home/cltbld', 15:49:11 INFO - 'LANG': 'en_US.UTF-8', 15:49:11 INFO - 'LANGUAGE': 'en_US:en', 15:49:11 INFO - 'LOGNAME': 'cltbld', 15:49:11 INFO - 'MAIL': '/var/mail/cltbld', 15:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:11 INFO - 'MOZ_NO_REMOTE': '1', 15:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:11 INFO - 'NO_EM_RESTART': '1', 15:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:11 INFO - 'PWD': '/builds/slave/test', 15:49:11 INFO - 'SHELL': '/bin/bash', 15:49:11 INFO - 'SHLVL': '1', 15:49:11 INFO - 'TERM': 'linux', 15:49:11 INFO - 'TMOUT': '86400', 15:49:11 INFO - 'USER': 'cltbld', 15:49:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:11 INFO - '_': '/tools/buildbot/bin/python'} 15:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:49:11 INFO - Cleaning up... 15:49:11 INFO - Return code: 0 15:49:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:49:11 INFO - Using env: {'DISPLAY': ':0', 15:49:11 INFO - 'HOME': '/home/cltbld', 15:49:11 INFO - 'LANG': 'en_US.UTF-8', 15:49:11 INFO - 'LANGUAGE': 'en_US:en', 15:49:11 INFO - 'LOGNAME': 'cltbld', 15:49:11 INFO - 'MAIL': '/var/mail/cltbld', 15:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:11 INFO - 'MOZ_NO_REMOTE': '1', 15:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:11 INFO - 'NO_EM_RESTART': '1', 15:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:11 INFO - 'PWD': '/builds/slave/test', 15:49:11 INFO - 'SHELL': '/bin/bash', 15:49:11 INFO - 'SHLVL': '1', 15:49:11 INFO - 'TERM': 'linux', 15:49:11 INFO - 'TMOUT': '86400', 15:49:11 INFO - 'USER': 'cltbld', 15:49:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:11 INFO - '_': '/tools/buildbot/bin/python'} 15:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:11 INFO - Downloading/unpacking psutil>=3.1.1 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:12 INFO - Installing collected packages: psutil 15:49:12 INFO - Running setup.py install for psutil 15:49:12 INFO - building 'psutil._psutil_linux' extension 15:49:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:49:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:49:13 INFO - building 'psutil._psutil_posix' extension 15:49:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:49:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:14 INFO - Successfully installed psutil 15:49:14 INFO - Cleaning up... 15:49:14 INFO - Return code: 0 15:49:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:49:14 INFO - Using env: {'DISPLAY': ':0', 15:49:14 INFO - 'HOME': '/home/cltbld', 15:49:14 INFO - 'LANG': 'en_US.UTF-8', 15:49:14 INFO - 'LANGUAGE': 'en_US:en', 15:49:14 INFO - 'LOGNAME': 'cltbld', 15:49:14 INFO - 'MAIL': '/var/mail/cltbld', 15:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:14 INFO - 'MOZ_NO_REMOTE': '1', 15:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:14 INFO - 'NO_EM_RESTART': '1', 15:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:14 INFO - 'PWD': '/builds/slave/test', 15:49:14 INFO - 'SHELL': '/bin/bash', 15:49:14 INFO - 'SHLVL': '1', 15:49:14 INFO - 'TERM': 'linux', 15:49:14 INFO - 'TMOUT': '86400', 15:49:14 INFO - 'USER': 'cltbld', 15:49:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:14 INFO - '_': '/tools/buildbot/bin/python'} 15:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:15 INFO - Installing collected packages: mozsystemmonitor 15:49:15 INFO - Running setup.py install for mozsystemmonitor 15:49:15 INFO - Successfully installed mozsystemmonitor 15:49:15 INFO - Cleaning up... 15:49:15 INFO - Return code: 0 15:49:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:49:15 INFO - Using env: {'DISPLAY': ':0', 15:49:15 INFO - 'HOME': '/home/cltbld', 15:49:15 INFO - 'LANG': 'en_US.UTF-8', 15:49:15 INFO - 'LANGUAGE': 'en_US:en', 15:49:15 INFO - 'LOGNAME': 'cltbld', 15:49:15 INFO - 'MAIL': '/var/mail/cltbld', 15:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:15 INFO - 'MOZ_NO_REMOTE': '1', 15:49:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:15 INFO - 'NO_EM_RESTART': '1', 15:49:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:15 INFO - 'PWD': '/builds/slave/test', 15:49:15 INFO - 'SHELL': '/bin/bash', 15:49:15 INFO - 'SHLVL': '1', 15:49:15 INFO - 'TERM': 'linux', 15:49:15 INFO - 'TMOUT': '86400', 15:49:15 INFO - 'USER': 'cltbld', 15:49:15 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:15 INFO - '_': '/tools/buildbot/bin/python'} 15:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:15 INFO - Downloading/unpacking jsonschema==2.5.1 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:17 INFO - warning: no files found matching '*.txt' 15:49:17 INFO - no previously-included directories found matching 'build' 15:49:17 INFO - no previously-included directories found matching 'dist' 15:49:17 INFO - no previously-included directories found matching '.git*' 15:49:17 INFO - Installing collected packages: jsonschema, functools32 15:49:17 INFO - Running setup.py install for functools32 15:49:17 INFO - warning: no files found matching '*.txt' 15:49:17 INFO - no previously-included directories found matching 'build' 15:49:17 INFO - no previously-included directories found matching 'dist' 15:49:17 INFO - no previously-included directories found matching '.git*' 15:49:17 INFO - Successfully installed jsonschema functools32 15:49:17 INFO - Cleaning up... 15:49:17 INFO - Return code: 0 15:49:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:49:17 INFO - Using env: {'DISPLAY': ':0', 15:49:17 INFO - 'HOME': '/home/cltbld', 15:49:17 INFO - 'LANG': 'en_US.UTF-8', 15:49:17 INFO - 'LANGUAGE': 'en_US:en', 15:49:17 INFO - 'LOGNAME': 'cltbld', 15:49:17 INFO - 'MAIL': '/var/mail/cltbld', 15:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:17 INFO - 'MOZ_NO_REMOTE': '1', 15:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:17 INFO - 'NO_EM_RESTART': '1', 15:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:17 INFO - 'PWD': '/builds/slave/test', 15:49:17 INFO - 'SHELL': '/bin/bash', 15:49:17 INFO - 'SHLVL': '1', 15:49:17 INFO - 'TERM': 'linux', 15:49:17 INFO - 'TMOUT': '86400', 15:49:17 INFO - 'USER': 'cltbld', 15:49:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:17 INFO - '_': '/tools/buildbot/bin/python'} 15:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:17 INFO - Cleaning up... 15:49:17 INFO - Return code: 0 15:49:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:49:17 INFO - Using env: {'DISPLAY': ':0', 15:49:17 INFO - 'HOME': '/home/cltbld', 15:49:17 INFO - 'LANG': 'en_US.UTF-8', 15:49:17 INFO - 'LANGUAGE': 'en_US:en', 15:49:17 INFO - 'LOGNAME': 'cltbld', 15:49:17 INFO - 'MAIL': '/var/mail/cltbld', 15:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:17 INFO - 'MOZ_NO_REMOTE': '1', 15:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:17 INFO - 'NO_EM_RESTART': '1', 15:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:17 INFO - 'PWD': '/builds/slave/test', 15:49:17 INFO - 'SHELL': '/bin/bash', 15:49:17 INFO - 'SHLVL': '1', 15:49:17 INFO - 'TERM': 'linux', 15:49:17 INFO - 'TMOUT': '86400', 15:49:17 INFO - 'USER': 'cltbld', 15:49:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:17 INFO - '_': '/tools/buildbot/bin/python'} 15:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:19 INFO - Downloading docopt-0.6.1.tar.gz 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:19 INFO - Running setup.py install for blobuploader 15:49:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:19 INFO - Running setup.py install for requests 15:49:19 INFO - Running setup.py install for docopt 15:49:19 INFO - Successfully installed blobuploader requests docopt 15:49:19 INFO - Cleaning up... 15:49:19 INFO - Return code: 0 15:49:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:19 INFO - Using env: {'DISPLAY': ':0', 15:49:19 INFO - 'HOME': '/home/cltbld', 15:49:19 INFO - 'LANG': 'en_US.UTF-8', 15:49:19 INFO - 'LANGUAGE': 'en_US:en', 15:49:19 INFO - 'LOGNAME': 'cltbld', 15:49:19 INFO - 'MAIL': '/var/mail/cltbld', 15:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:19 INFO - 'MOZ_NO_REMOTE': '1', 15:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:19 INFO - 'NO_EM_RESTART': '1', 15:49:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:19 INFO - 'PWD': '/builds/slave/test', 15:49:19 INFO - 'SHELL': '/bin/bash', 15:49:19 INFO - 'SHLVL': '1', 15:49:19 INFO - 'TERM': 'linux', 15:49:19 INFO - 'TMOUT': '86400', 15:49:19 INFO - 'USER': 'cltbld', 15:49:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:19 INFO - '_': '/tools/buildbot/bin/python'} 15:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:20 INFO - Running setup.py (path:/tmp/pip-tgdGNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:20 INFO - Running setup.py (path:/tmp/pip-LKQM9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:20 INFO - Running setup.py (path:/tmp/pip-TQ97d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:20 INFO - Running setup.py (path:/tmp/pip-lebatK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:20 INFO - Running setup.py (path:/tmp/pip-qgZWUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:20 INFO - Running setup.py (path:/tmp/pip-GY0dwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:20 INFO - Running setup.py (path:/tmp/pip-Xbanvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:20 INFO - Running setup.py (path:/tmp/pip-yyybTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:20 INFO - Running setup.py (path:/tmp/pip-jglq2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:20 INFO - Running setup.py (path:/tmp/pip-hza0V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:20 INFO - Running setup.py (path:/tmp/pip-1p4Wr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:20 INFO - Running setup.py (path:/tmp/pip-tLv4JN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:21 INFO - Running setup.py (path:/tmp/pip-RQdg2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:21 INFO - Running setup.py (path:/tmp/pip-1VJ2xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:21 INFO - Running setup.py (path:/tmp/pip-e5xlGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:21 INFO - Running setup.py (path:/tmp/pip-USm15W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:21 INFO - Running setup.py (path:/tmp/pip-Uo5ZQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:21 INFO - Running setup.py install for manifestparser 15:49:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:21 INFO - Running setup.py install for mozcrash 15:49:21 INFO - Running setup.py install for mozdebug 15:49:21 INFO - Running setup.py install for mozdevice 15:49:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:21 INFO - Running setup.py install for mozfile 15:49:21 INFO - Running setup.py install for mozhttpd 15:49:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozinfo 15:49:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozInstall 15:49:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozleak 15:49:22 INFO - Running setup.py install for mozlog 15:49:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for moznetwork 15:49:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozprocess 15:49:22 INFO - Running setup.py install for mozprofile 15:49:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozrunner 15:49:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:22 INFO - Running setup.py install for mozscreenshot 15:49:23 INFO - Running setup.py install for moztest 15:49:23 INFO - Running setup.py install for mozversion 15:49:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:23 INFO - Cleaning up... 15:49:23 INFO - Return code: 0 15:49:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:23 INFO - Using env: {'DISPLAY': ':0', 15:49:23 INFO - 'HOME': '/home/cltbld', 15:49:23 INFO - 'LANG': 'en_US.UTF-8', 15:49:23 INFO - 'LANGUAGE': 'en_US:en', 15:49:23 INFO - 'LOGNAME': 'cltbld', 15:49:23 INFO - 'MAIL': '/var/mail/cltbld', 15:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:23 INFO - 'MOZ_NO_REMOTE': '1', 15:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:23 INFO - 'NO_EM_RESTART': '1', 15:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:23 INFO - 'PWD': '/builds/slave/test', 15:49:23 INFO - 'SHELL': '/bin/bash', 15:49:23 INFO - 'SHLVL': '1', 15:49:23 INFO - 'TERM': 'linux', 15:49:23 INFO - 'TMOUT': '86400', 15:49:23 INFO - 'USER': 'cltbld', 15:49:23 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:23 INFO - '_': '/tools/buildbot/bin/python'} 15:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:23 INFO - Running setup.py (path:/tmp/pip-mkiouv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:23 INFO - Running setup.py (path:/tmp/pip-HeEK6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:23 INFO - Running setup.py (path:/tmp/pip-dnXhC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:23 INFO - Running setup.py (path:/tmp/pip-EBMd_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:23 INFO - Running setup.py (path:/tmp/pip-ZNcSuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:23 INFO - Running setup.py (path:/tmp/pip-1f_JUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:23 INFO - Running setup.py (path:/tmp/pip-ee4FVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:23 INFO - Running setup.py (path:/tmp/pip-xUQVIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:24 INFO - Running setup.py (path:/tmp/pip-PiBDYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:24 INFO - Running setup.py (path:/tmp/pip-12MoJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:24 INFO - Running setup.py (path:/tmp/pip-kImtJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:24 INFO - Running setup.py (path:/tmp/pip-Rzkjv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:24 INFO - Running setup.py (path:/tmp/pip-ERAg77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:24 INFO - Running setup.py (path:/tmp/pip-LbjI86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:24 INFO - Running setup.py (path:/tmp/pip-0I1a6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:24 INFO - Running setup.py (path:/tmp/pip-9dwVW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:24 INFO - Running setup.py (path:/tmp/pip-WNFwE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:49:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:26 INFO - Downloading blessings-1.6.tar.gz 15:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:26 INFO - Installing collected packages: blessings 15:49:26 INFO - Running setup.py install for blessings 15:49:27 INFO - Successfully installed blessings 15:49:27 INFO - Cleaning up... 15:49:27 INFO - Return code: 0 15:49:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:27 INFO - Reading from file tmpfile_stdout 15:49:27 INFO - Current package versions: 15:49:27 INFO - argparse == 1.2.1 15:49:27 INFO - blessings == 1.6 15:49:27 INFO - blobuploader == 1.2.4 15:49:27 INFO - docopt == 0.6.1 15:49:27 INFO - functools32 == 3.2.3-2 15:49:27 INFO - jsonschema == 2.5.1 15:49:27 INFO - manifestparser == 1.1 15:49:27 INFO - mozInstall == 1.12 15:49:27 INFO - mozcrash == 1.0 15:49:27 INFO - mozdebug == 0.1 15:49:27 INFO - mozdevice == 0.50 15:49:27 INFO - mozfile == 1.2 15:49:27 INFO - mozhttpd == 0.7 15:49:27 INFO - mozinfo == 0.9 15:49:27 INFO - mozleak == 0.1 15:49:27 INFO - mozlog == 3.4 15:49:27 INFO - moznetwork == 0.27 15:49:27 INFO - mozprocess == 0.25 15:49:27 INFO - mozprofile == 0.28 15:49:27 INFO - mozrunner == 6.13 15:49:27 INFO - mozscreenshot == 0.1 15:49:27 INFO - mozsystemmonitor == 0.3 15:49:27 INFO - moztest == 0.8 15:49:27 INFO - mozversion == 1.4 15:49:27 INFO - psutil == 3.1.1 15:49:27 INFO - requests == 1.2.3 15:49:27 INFO - wsgiref == 0.1.2 15:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:49:27 INFO - Using env: {'DISPLAY': ':0', 15:49:27 INFO - 'HOME': '/home/cltbld', 15:49:27 INFO - 'LANG': 'en_US.UTF-8', 15:49:27 INFO - 'LANGUAGE': 'en_US:en', 15:49:27 INFO - 'LOGNAME': 'cltbld', 15:49:27 INFO - 'MAIL': '/var/mail/cltbld', 15:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:27 INFO - 'MOZ_NO_REMOTE': '1', 15:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:27 INFO - 'NO_EM_RESTART': '1', 15:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:27 INFO - 'PWD': '/builds/slave/test', 15:49:27 INFO - 'SHELL': '/bin/bash', 15:49:27 INFO - 'SHLVL': '1', 15:49:27 INFO - 'TERM': 'linux', 15:49:27 INFO - 'TMOUT': '86400', 15:49:27 INFO - 'USER': 'cltbld', 15:49:27 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:27 INFO - '_': '/tools/buildbot/bin/python'} 15:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:49:27 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:28 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:49:28 INFO - Installing collected packages: marionette-driver 15:49:28 INFO - Running setup.py install for marionette-driver 15:49:28 INFO - Successfully installed marionette-driver 15:49:28 INFO - Cleaning up... 15:49:28 INFO - Return code: 0 15:49:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe05a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfab5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:49:28 INFO - Using env: {'DISPLAY': ':0', 15:49:28 INFO - 'HOME': '/home/cltbld', 15:49:28 INFO - 'LANG': 'en_US.UTF-8', 15:49:28 INFO - 'LANGUAGE': 'en_US:en', 15:49:28 INFO - 'LOGNAME': 'cltbld', 15:49:28 INFO - 'MAIL': '/var/mail/cltbld', 15:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:28 INFO - 'MOZ_NO_REMOTE': '1', 15:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:28 INFO - 'NO_EM_RESTART': '1', 15:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:28 INFO - 'PWD': '/builds/slave/test', 15:49:28 INFO - 'SHELL': '/bin/bash', 15:49:28 INFO - 'SHLVL': '1', 15:49:28 INFO - 'TERM': 'linux', 15:49:28 INFO - 'TMOUT': '86400', 15:49:28 INFO - 'USER': 'cltbld', 15:49:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:28 INFO - '_': '/tools/buildbot/bin/python'} 15:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:29 INFO - Cleaning up... 15:49:29 INFO - Return code: 0 15:49:29 INFO - Running post-action listener: _resource_record_post_action 15:49:29 INFO - Running post-action listener: _start_resource_monitoring 15:49:29 INFO - Starting resource monitoring. 15:49:29 INFO - [mozharness: 2017-05-23 22:49:29.104247Z] Finished create-virtualenv step (success) 15:49:29 INFO - [mozharness: 2017-05-23 22:49:29.105352Z] Running install step. 15:49:29 INFO - Running pre-action listener: _resource_record_pre_action 15:49:29 INFO - Running main action method: install 15:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:29 INFO - Reading from file tmpfile_stdout 15:49:29 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:29 INFO - Reading from file tmpfile_stdout 15:49:29 INFO - Output received: 15:49:29 INFO - Usage: mozinstall [options] installer 15:49:29 INFO - Options: 15:49:29 INFO - -h, --help show this help message and exit 15:49:29 INFO - -d DEST, --destination=DEST 15:49:29 INFO - Directory to install application into. [default: 15:49:29 INFO - "/builds/slave/test"] 15:49:29 INFO - --app=APP Application being installed. [default: firefox] 15:49:29 INFO - mkdir: /builds/slave/test/build/application 15:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:49:45 INFO - Reading from file tmpfile_stdout 15:49:45 INFO - Output received: 15:49:45 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:45 INFO - Running post-action listener: _resource_record_post_action 15:49:45 INFO - [mozharness: 2017-05-23 22:49:45.083159Z] Finished install step (success) 15:49:45 INFO - [mozharness: 2017-05-23 22:49:45.083436Z] Running run-tests step. 15:49:45 INFO - Running pre-action listener: _resource_record_pre_action 15:49:45 INFO - Running main action method: run_tests 15:49:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:45 INFO - Python 2.7.3 15:49:45 INFO - Return code: 0 15:49:45 INFO - grabbing minidump binary from tooltool 15:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa87a0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfa96a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa9b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:49:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:49:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:49:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:45 INFO - Return code: 0 15:49:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:45 INFO - ENV: RUST_BACKTRACE is now 1 15:49:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:49:45 INFO - Using env: {'DISPLAY': ':0', 15:49:45 INFO - 'HOME': '/home/cltbld', 15:49:45 INFO - 'LANG': 'en_US.UTF-8', 15:49:45 INFO - 'LANGUAGE': 'en_US:en', 15:49:45 INFO - 'LOGNAME': 'cltbld', 15:49:45 INFO - 'MAIL': '/var/mail/cltbld', 15:49:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:45 INFO - 'MOZ_NO_REMOTE': '1', 15:49:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:45 INFO - 'NO_EM_RESTART': '1', 15:49:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:45 INFO - 'PWD': '/builds/slave/test', 15:49:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:45 INFO - 'RUST_BACKTRACE': '1', 15:49:45 INFO - 'SHELL': '/bin/bash', 15:49:45 INFO - 'SHLVL': '1', 15:49:45 INFO - 'TERM': 'linux', 15:49:45 INFO - 'TMOUT': '86400', 15:49:45 INFO - 'USER': 'cltbld', 15:49:45 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548', 15:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:45 INFO - '_': '/tools/buildbot/bin/python'} 15:49:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:49:45 INFO - mozversion application_buildid: 20170523221158 15:49:45 INFO - mozversion application_changeset: 96e18bec9fc8a5ce623c16167c12756bbe190d73 15:49:45 INFO - mozversion application_display_name: Nightly 15:49:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:45 INFO - mozversion application_name: Firefox 15:49:45 INFO - mozversion application_remotingname: firefox 15:49:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:49:45 INFO - mozversion application_vendor: Mozilla 15:49:45 INFO - mozversion application_version: 55.0a1 15:49:45 INFO - mozversion platform_buildid: 20170523221158 15:49:45 INFO - mozversion platform_changeset: 96e18bec9fc8a5ce623c16167c12756bbe190d73 15:49:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:49:45 INFO - mozversion platform_version: 55.0a1 15:49:45 INFO - using testdate: 1495579785 15:49:45 INFO - actual date: 1495579785 15:49:45 INFO - starting webserver on 'localhost:41066' 15:49:45 INFO - SUITE-START | Running 2 tests 15:49:45 INFO - TEST-START | dromaeo_css 15:49:45 INFO - operating with platform_type : linux_ 15:49:45 INFO - Initialising browser for dromaeo_css test... 15:49:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41066/getInfo.html -profile /tmp/tmpv4s9_i/profile 15:49:45 INFO - TEST-INFO | started process 3335 (/builds/slave/test/build/application/firefox/firefox http://localhost:41066/getInfo.html) 15:50:23 INFO - TEST-INFO | 3335: killed by SIGTERM 15:50:23 INFO - Browser initialized. 15:50:23 INFO - Running cycle 1/1 for dromaeo_css test... 15:50:23 INFO - TEST-INFO | started process 3532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv4s9_i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:35 INFO - PID 3532 | RSS: Main: 173539328 15:50:35 INFO - PID 3532 | 15:53:39 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41066/tests/dromaeo/cssquery-ext.html) 15:53:41 INFO - PID 3532 | RSS: Main: 170926080 15:53:41 INFO - PID 3532 | 15:56:43 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-ext.html (next: http://localhost:41066/tests/dromaeo/cssquery-jquery.html) 15:56:44 INFO - PID 3532 | RSS: Main: 175882240 15:56:44 INFO - PID 3532 | 15:59:45 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41066/tests/dromaeo/cssquery-mootools.html) 15:59:46 INFO - PID 3532 | RSS: Main: 174252032 15:59:46 INFO - PID 3532 | 16:02:48 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41066/tests/dromaeo/cssquery-prototype.html) 16:02:48 INFO - PID 3532 | RSS: Main: 179511296 16:02:48 INFO - PID 3532 | 16:05:51 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41066/tests/dromaeo/cssquery-yui.html) 16:05:52 INFO - PID 3532 | RSS: Main: 176693248 16:05:52 INFO - PID 3532 | 16:08:53 INFO - PID 3532 | Cycle 1(1): loaded http://localhost:41066/tests/dromaeo/cssquery-yui.html (next: http://localhost:41066/tests/dromaeo/cssquery-dojo.html) 16:08:54 INFO - PID 3532 | RSS: Main: 174276608 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | __start_tp_report 16:08:54 INFO - PID 3532 | _x_x_mozilla_page_load 16:08:54 INFO - PID 3532 | _x_x_mozilla_page_load_details 16:08:54 INFO - PID 3532 | |i|pagename|runs| 16:08:54 INFO - PID 3532 | |0;dojo.html;2678.2370769069457;2683.2084535062154;2695.2992222022267;2721.4829182455337;2726.822756520826;5147.948520514794;5194.610404219689;5222.086134926382;5235.031519168956;5261.316028916235;5191.948080519193;5248;5257.1851363038695;5334.839954801362;5356.785728570852;4671.252599584067;4702.24764037754;4710.057988402316;4734.289856521515;4778.78495467703;4709.929351059737;4717.268823332389;4793.3528973588645;4801.831935882245;4851.126797176506;4564.863054108382;4599.218132917378;4719.244920812654;4726.621870250372;4776.283557466373;4688.671792974493;4727.959848833251;4740.715557066553;4755.453122890843;4759.571638552512;4692.249240121564;4725.66920315578;4733.053389322149;4733.692309999839;4743.596794272491;5218.504242096995;5263.105272103751;5269.578433725293;5291.28567643367;5300.867478313035;5543.196236545718;5596.524295434893;5627.8593035174445;5693.459121383462;5728.79949201776;5671.886562268732;5694.857628559278;5699.458551437607;5705.743241554099;5708.429157084258;5252.606054545924;5257.369115706063;5258.947410525922;5262.947370526323;5266.15741481365;5606.635568688022;5776.740046697824;5804.303483581997;5808.88382232353;5856.355800861902;5452.127659574449;5508.862278443071;5589.217509548666;5605.607607467438;5610.831675049755;5624.015797235549;5632.155176723524;5633.633813802172;5648.350439699405;5666.546676265889;5605;5613.831585052455;5614.326280846324;5636.520895723827;5664.2636457261115;5482.890342193134;5542;5573.191887176336;5579.9163012554845;5714.828555143353;4759.190937540627;4781.235002399669;4782.33047373368;4789.233722604366;4797.544233297831;4030.5969403059457;4039.818208180639;4042.838286468508;4043.3732771420478;4089.1003979124544;4753.952460475421;4776.09254241693;4785.353977213032;4802.159622066124;4822.035592881438;5547.057000309957;5634.549236061187;5673.404292549306;5784.3926387729525;5788.82633520995;4786.497417771154;4806.8798280043275;4836.709797412166;4862.805487780449;4876.634252431011;5083.466236045236;5090.185570308733;5095.0574143783515;5101.795928162906;5106.565941894906;5035.446100928822;5151.922721159111;5253.028189784902;5254.894902102013;5262.368515778131;5600.915986260128;5642.887142257214;5644;5695.572832037531;5732.971335143465;5229.947700522946;5252.185911183773;5264.894702106013;5277.683338999673;5288.9735551322;5633.63381380209;5675.971620141873;5677.063284558167;5687.374388817144;5720.914186287292;5496.1755736639825;5576.247206627054;5577.637453565505;5589.469000444951;5766.913496297475;5445.1832225166545;5480.671159730429;5486.369067557282;5490.0941344677685;5582;5549.944500554942;5585.916211256916;5589.105743081088;5606.6916319602815;5645.971770141287;5472;5476.561875050066;5496.285482887359;5505.614606977473;5517.503424691797;5413.133898576209;5494.615376923577;5609.242752228397;5609.775608975759;5649.406812284651;5295.36455625312;5301;5345.572354211576;5358.33020872391;5359.249705041219 16:08:54 INFO - PID 3532 | |1;ext.html;632.6204277433537;680.2924958043633;693.9340762627552;1174.4421399835073;1199.478226971267;34203.144921376945;34806.477902831415;34901.65098349016;35253.47119793199;35282.294354112935;32991;33002.504962425584;33129.33741325172;33152.83423582885;33293.66706332936;6245.09446130312;6458.063580780784;6597.80206593803;6601.7359305627715;6604.57070290432;6564.474842012628;6654;6667.266600673922;6669.166354205724;6682.93317066828;6644.568103073285;6677.131972843524;6727.562708423961;6728.764493242737;6735.9663201683925;6622.93377066228;6655.201375834882;6660.50046246531;6664.533482656216;6670.366315200048;6587.2095348558005;6660.367265109807;6688.83277918051;6697.464202863759;6704.463642908604;31356.529652055237;31662.366752664817;31703.52444713331;31777.205569860707;31837.52243716346;2918.2266699324696;2924.3274046969104;2933.8239705617516;2937.412517496488;2941.5734718465915;24928.626070608956;24994.750052499425;25050;25111.748882511307;25144.622830657554;2841.105051908642;2851.1019029006006;2855.2433605094666;2860.113364856901;2863.656361236644;141861;141999;142114;147693.2615336927;152545.23727381404;38796.6120338793;38918.22163556713;38933.61066389357;39086.804565976985;39118.60881391207;41003;41107.7944610275;41191.38212926809;41264.38103428451;43370.7831460847;12767.61697149087;12880.613581592566;12899.806502902464;12934.741305174031;12949.611511654666;10214.08073273394;10226.6420675276;10264.692059238234;10268.486575671186;10330.896691033144;12677.429515671816;12799.744005119845;12841.871581284255;12886.226826390446;12921;5230.790768369298;5376.569874410116;5381.977424289373;5411.702356370359;5486.561075114061;854.5556310718391;892.834825557274;897.4794619120894;905.063259526387;920.300571565615;19567.608647826964;20287;20472.488187795425;20596.691049634268;21060.26289079875;968.4334664221368;970.2916870684439;985.9852102218473;1001.5242759689235;1001.5643195210107;10953.726156846142;10991.505382257657;11104.833427498594;11220.551177952953;11348.886511134948;167490;169050;169064;169086.15456922638;169418.15290923466;39858;39962.60037399647;40133;40157.598424015385;40176.79911600423;1155.4684844971239;1189.2864281431073;1197.5748609243767;1204.084895479442;1220.3288191494642;19134.80865191386;19818;20645.17419303211;20697;20896.268630598464;29188;35169.29661406809;36993.81503092467;37086.814565927;37250.254994899406;158896;159883;160481;160526;160656;29075.127746167647;29269.121926342243;29487;29572;29605.5559166617;34599.30801383908;34880.47679284863;35308.47037294494;35588.466173007946;35692.10769730777;26916.461670766083;26962.75415342236;27681.72318276872;27697.446051079267;28144.577831332135;26555.203343899713;27309.86345068262;27387.178384648494;27481.862590687717;28086.157415277576;30360.54459183158;30399.54400683947;30399.69600304057;30469.695303047574;30527.694723052486;26313.868430657723;26452.73547264503;26692.59961100624;27076.45847083008;27950.16149515518;21174.788252117898;22084.668729968744;22128.778712212672;22287.554248914606;22434.551308974056 16:08:54 INFO - PID 3532 | |2;jquery.html;422.6944156843375;424.1559296998973;426.9893252668682;437.79642466253193;452.49998751379746;16895.986240825543;16929.746053809187;17656.911715441438;17699.46901592951;17771.733423998652;17510.599152067836;17771.20029598668;18172.909135454338;18225.270989160417;18500.16749246284;5052.747362631872;5267.446918073604;5277.366715994086;5380;5553.111502159655;5309.230161626581;5385.7576409061685;5386.218998245252;5389.8113566025195;5407.783688652469;5353.518183363496;5357.4106848246665;5414.242006119146;5437.32033495813;5439.0209762242785;5311.725185955363;5377.784888604451;5396.082665946798;5418.566514678816;5418.810341638064;4724.76376181193;4851.02979404117;4854.514548545152;4855.732934688591;4887.706737595756;21995;22194.88902555477;22387.77612223873;22451;22467.32598022062;1496.6408062065134;1542.1518165009313;1550.178405445116;1564.9295781689736;1565.2173913043478;14804.333804978694;14812.481563145346;14932.776008359884;15012.924935375362;15335.386584536605;2071.59603877244;2100.789921007902;2115.724955755742;2126.946826329339;2148.1729534129317;80703.59648201721;86345.56827215824;86410;93750;96463.51768241184;10623.628173013907;11095.44522773858;11148.721281968015;11229.71925701864;11329;15838.366465341258;15949.122798245975;16022.919885400732;16035.839641603434;16087.276072576527;7391.15001774792;7648.34989025939;7684.884726729104;7762.146163921964;7851.725189618336;6733.663316834139;6818.28408017161;6842;6927.965360173167;7062.717491300393;6893.20728116263;6990.685419156151;7027.929720702829;7061.858762824818;7099.89350159748;1950.3563823938066;1962.3720409468833;1965.164804957887;1987.731656226391;2083.2083808152806;764.75910088322;823.1644880446352;826.4504104770381;833.1959658929162;838.1702114906199;16055.357785688675;16367.59081022984;16484.752728709078;16508.504744857673;17170.484885453207;835.7492752174323;840.80549765113;846.3821410370464;851;867.0159369115972;8678.305735541267;8728.607212675444;9055.411398259092;9204.263658907272;9328.720138395858;342919;348016;352514;354544;356016;73823.63088184631;75022;76388.9999999989;77511.61244193742;77766.2223377759;1060.8090543702053;1080.33559360994;1112.7996960547018;1117.3742704085737;1119.0655802405083;17278.827211727723;17374.218160183074;17786.644267114843;18322.450326490227;18487;37758.81120594379;37836.81081594574;38000.52996555215;38461.80769096249;38717.419238712006;47137.52862471469;47386.52613473821;47441.762791187204;47515.524844752494;49676.00647987092;8241.95879020601;8263.586820659064;8561.700340488302;8738.69414570487;8905.55472226373;13062.934685326512;13235.867641323464;13300.46798128064;13327.067105302924;13605.65985850322;10427.582896684047;10493.422861742372;10848.945755271174;10978.121750259801;11310.60412885545;9233.399829011092;9382.859257111013;10221.182305415401;10645.0951669109;10758.46207689628;10628.89371106279;10884.129269658566;11060.944695276472;11193.27243729155;11345.319280843176;8506;8635.481871087755;8712.954445466585;8758.386912916034;8902.198802107841;10647.627333043307;10717.249792514762;10864.891351086704;10940.179486538382;11153.219274650697 16:08:54 INFO - PID 3532 | |3;mootools.html;1202.7895118354288;1252.7494501099782;1267;1269.8793614606616;1284.5504073574248;9797.363171393863;10081.94959025204;10231.693049208527;10260.846087308688;10589.84115238272;10456.4248966307;10596.841047384296;10658.147348212144;10661;10666;2268.8411811173182;2277.5786479501344;2296.8966396512196;2320.5010922651627;2320.9767902321014;2097;2153.7307836520436;2161.91352345906;2169.240765731997;2169.652855543113;2168.631332673441;2210.557888422314;2216.944576385587;2217.7671344508835;2238.2613737466677;2086.457521044532;2110.6095372356085;2128.4040468668795;2129.6060228857555;2137.8289736821016;2124.702541644171;2162.135145941636;2183.7379514458207;2227.7660845611304;2239.55208958209;10215.489225538693;10267.845982310271;10289;10307.639232626898;10316.484175791256;5531.668099913978;5687.7156142193135;5693.715314234313;5739.913901291484;5751.712414379264;3263.9347213055844;3473;3544.3620148373284;3546.0248431681234;3552.0942159749357;1045.3571053801934;1069.2675517270695;1086.8478413022183;1108.983365249522;1119.9552017919273;14377.856221437756;14385;14414.495492657812;14487.058341207789;14492.710145797026;4762.9523704763205;4909.386326709161;4941.407031156285;5010.248462730619;5034.974825125851;10617.416042117758;10628.09661178793;10660.147188224923;10671.573137074585;10672.519736611872;5625.8593535161945;5641.984442800335;5656.85857853557;5694.259746232963;5708.058170401837;5155.948440515622;5257.316548848626;5276;5278.261043453919;5308.177232528964;3788.3559794834946;3939.468171796828;3977.5028121484816;4044.988752811797;4046.736962097454;1168.7662467506532;1187.145255416099;1189.108168873345;1196.4634973855482;1196.5154112584416;699.1260923845193;724.051492544768;728.8068661804626;733.8018734941643;741.4142827166586;5565.777368905198;5568.6658800472105;5642.746076426571;5662.150677398423;5674.808290259092;544.7548603128608;563.760401829221;575.6488541989384;576.2911618708928;580.5181699189662;3510.4909788081095;3542.3977923753023;3686.4101743721412;3831.501904752364;3928.3321835287497;12363;13137.34313284332;13255.73488530224;13318.467261309439;13390.598282051555;12623.810642840364;12973.767492088236;13013.804792928113;13039.413226404835;13163.736725265633;7344.265573442613;7395.4083673306;7450.478466507292;7466.2160473150625;7478.663460144307;6017.127516510169;6153.261608606996;6172.938270617416;6255.655938923221;6292.811215663537;11013.724656883322;11467.483963221508;11515.136364772508;11694.415279236175;11718;11719.941400292944;11895.286282823059;11963.342016188844;12133.635990920286;12172.45223964906;9347.252219822261;9669.613215471303;9682.95158524231;9698.418094914328;9709.5145242739;9599;9602.375845570015;9654.951725241328;9668.226541876773;9693.321467497208;7893.36853051743;7928.127905930459;7990.3208227299;8065.516069035877;8187.549684767395;7657.808554786175;7735.574543400167;7816.452848300564;7916.6041697916025;8000.719974800854;7080.221175670775;7095.46783991213;7117.82205444868;7131.572105673677;7142.892856607259;5279.762410691451;5334.093204155381;5379.919301210407;5384.246205531151;5401.4868587484125;5348.5988550858065;5415.323084614423;5511.862203444785;5517.586181036358;5520.447955204448 16:08:54 INFO - PID 3532 | |4;prototype.html;362.5848403577903;383.1111820576264;387.2081593142025;392.0727479510957;392.1177350960339;5475.178723191523;5500.834974950748;5506.394296627373;5510.448955104487;5593.972030139854;5361.919571206426;5381.246625472427;5396.730163491819;5399.919001214985;5403;4212.967822883398;4270.893227669302;4282.421873047146;4283.421738065368;4295.226859165349;4268.615824575788;4283.121959998193;4285.164392943378;4286.828526858922;4296.849610263642;4248.596383343577;4254.87235382939;4264.104538047014;4278.25130602145;4282.935755963647;4169.520505141917;4235.538739134993;4279.636230920375;4283.785810709468;4283.915580781848;3841.9807900960595;3858.9614103858885;3862.324093283692;3867.961320386816;3873.1479074603826;5322.760475778599;5341.732913354355;5341.946580534222;5357.892842143136;5361.169018802092;4951.504849515056;5011.799528018875;5037.420696619863;5054.974725126388;5075.796968121271;5673.602847800656;5725.341585717654;5740.13897915316;5762.683052432115;5765.769369225226;4326.545712700153;4338.978305108486;4346.782660866944;4353.216421044211;4362.498312694049;5948.524118070587;6001.789937352171;6028.608140470856;6058.091286307045;6109.877802443971;4393.956060439355;4404.392396775154;4434.385975430014;4436.7559784211535;4444.466664000341;4469;4499.730016199039;4520.728756274634;4548.522405147478;4552.1806074906835;4578.336141259499;4759.762011899391;4773.785179666923;4780.617550595944;5037.748112594356;5743.483086522149;5745.827625171251;5759.164921086419;5761.798337058183;5789.3052833659385;1412.1950488221614;1435.8277006759256;1439.2515891736448;1439.4530078570285;1447.8045463862454;940.2571968145089;942.9528523573794;951.6383774165772;960.9327347085776;966.709987003896;4893.779779909913;4898.289747986522;4899.2896030075435;4899.779509921991;4908.134908734729;5891.617044892068;5927.347991720907;5941.603213621888;5950.9107363389585;5974.940250597525;4841.235797013222;4858.623256836536;4907.607391408679;4943.5169449165105;4943.826966056171;3119.2981579144966;3128.8748450061744;3153.1486498645695;3157.400093982182;3157.431662300762;5356.651817631841;5406.999705054499;5441.483059109379;5444.128939369682;5463;5899.734511946973;6078.3921607838865;6119.663418512025;6155.138280640714;6158.107074474266;5442.156465747816;5470.753816078286;5471.206675032098;5492.697901615449;5494.2857428534035;5901.29184497863;5924.348321684697;5931.1992880960515;5936.723604425069;5957.702114894325;5690.630109042899;5808.5062769665055;5859.707014649336;5884.293884733859;5890.4698577128265;5433.4566543345345;5438.428964958781;5438.592105592017;5465.562754979513;5471.945280547144;3958.267720471722;4007.8396864126275;4013.1973605279645;4013.899652508594;4024.0945787197647;5964;5986.341502434816;5992.280926288873;6005.699715014319;6034.607750496204;5765.596408251382;5795.333536643171;5821.330546987151;5843.1235314703135;5844.970775146097;5490.149026900677;5727.914081288867;5760.971195143998;5762.337331206965;5818.389069147848;5649.683629711799;5672.7730890763905;5703.572232082527;5705.771769129188;5741.397153299011;5623.690697011703;5769.048107062287;5841.561882858888;5853.2976042875125;5857.4435428634215;5647.3787883333625;5656.095024796013;5657.12314591239;5659;5681.914771278517 16:08:54 INFO - PID 3532 | |5;yui.html;688.5317983771035;692.4183685704007;701.3372363116854;708.4544900426674;708.7979925721171;12591;13158.934205328971;13169.012324075697;15404;16879.66240675189;13852.307384630752;13947.302634868242;14056;14160;16126.677466450694;855.7261676263598;865.7446053414227;868.7524055644111;869.4044579463057;870.3559366069095;850.5874650794391;858.5921687198593;870.316801310971;870.7344260000697;876.4040452492303;891.2914233184633;902.6840605787954;903.2412773270485;906.0667512462174;906.8413027720155;906.2387594420685;910.717677519971;912.7489940266413;913.2237598041678;914.3005600715425;666.6133642487346;669.8057563306635;672.1463741048841;672.5225090185974;675.7026908160394;14748.410063597443;14772.335244914004;14949.103053816698;15020.09879407239;15236.847631523653;13663.726725465534;13768.724625507533;14000.789988150185;14102.294885255696;14127.929360353133;11792.115591330685;12021;12041.638750837401;12057.819132713017;12142.453589588402;2867.0108812459875;2938.30949726814;2947.101134154082;2949.6607890092487;2953.3650265192873;69361.3063869365;69981;70090.99090091001;70736.6463167686;71459;12277.570285039981;12286.262824230575;12455.128141030222;12499.3750312484;12590.496380144692;20730.274440394816;22005.559888802134;22276.554468910854;22434.887825561094;22525;5996.430339117776;6024.3975602439405;6112.449879510777;6126.754929802847;6176.135341052256;2924.5905573219343;3015.050259168355;3016.080095570872;3019.8641061152302;3020.8489575521135;3497.143199916037;3568.1258091767163;3582.1044738815294;3587.695045921126;3653.598104208535;731.765834932827;744.6202436757185;746.4103358346954;748.472327009457;749.8950146979428;581.2037508613226;595.2529575382866;595.8510372406898;602.5601311042875;619.1641284266294;9237.30720195988;9499.572519236652;10066.245031622657;10078.496075196212;10258.948705256425;548.8957098151418;612.0574315553997;614.2567493333117;614.7080136935009;620.3114542857425;3851.9229615407535;4050.4936882889638;4055.6349928506575;4197.3074442717225;4219.936700949488;61604.38395616076;61910;62276;62409.375906240355;62458.37541624616;53605.73197133989;53804;54287.09995150151;54360.72819635877;54657;48286;48715.999999999294;50122.24816627753;50459;50653.746731266845;7800.063992320957;8123.228293312126;10186.796264074825;10913.126949844152;11007.394593297446;24879.751202487743;24905.87547062253;25025.874870625532;25446;25969.22092337233;41443.37834932539;42899.78550107229;43592;43820;45560.77219613881;13339.399727012114;13583.932080339535;13801.585952421443;13827;14011.859881401464;14395.136291822524;14804.555863324118;14804.925975370053;14911.850881491046;15067;12708.428120734405;12772;12791.42438590247;12819.679508012001;12825.166364186298;12678.239305641691;12795.296258705488;12898.226106433644;12997.870021299666;13139.671508211988;12775.425105870072;13243.933780331037;13316.134451260637;13447.798283025959;13470.93264533671;9234.538273086184;9261.629534818532;9376.3905346155;9570.856437153587;9957.0540798624;10183.439910804977;10254.897451025394;10627.893721063;10720.088792452729;10797.082248009006 16:08:54 INFO - PID 3532 | __end_tp_report 16:08:54 INFO - PID 3532 | __start_cc_report 16:08:54 INFO - PID 3532 | _x_x_mozilla_cycle_collect,337 16:08:54 INFO - PID 3532 | __end_cc_report 16:08:54 INFO - PID 3532 | __startTimestamp1495580934134__endTimestamp 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | ------- Summary: start ------- 16:08:54 INFO - PID 3532 | Number of tests: 6 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#0] dojo.html Cycles:165 Average:5180.60 Median:5295.36 stddev:604.80 (11.4%) stddev-sans-first:573.91 16:08:54 INFO - PID 3532 | Values: 2678.2 2683.2 2695.3 2721.5 2726.8 5147.9 5194.6 5222.1 5235.0 5261.3 5191.9 5248.0 5257.2 5334.8 5356.8 4671.3 4702.2 4710.1 4734.3 4778.8 4709.9 4717.3 4793.4 4801.8 4851.1 4564.9 4599.2 4719.2 4726.6 4776.3 4688.7 4728.0 4740.7 4755.5 4759.6 4692.2 4725.7 4733.1 4733.7 4743.6 5218.5 5263.1 5269.6 5291.3 5300.9 5543.2 5596.5 5627.9 5693.5 5728.8 5671.9 5694.9 5699.5 5705.7 5708.4 5252.6 5257.4 5258.9 5262.9 5266.2 5606.6 5776.7 5804.3 5808.9 5856.4 5452.1 5508.9 5589.2 5605.6 5610.8 5624.0 5632.2 5633.6 5648.4 5666.5 5605.0 5613.8 5614.3 5636.5 5664.3 5482.9 5542.0 5573.2 5579.9 5714.8 4759.2 4781.2 4782.3 4789.2 4797.5 4030.6 4039.8 4042.8 4043.4 4089.1 4754.0 4776.1 4785.4 4802.2 4822.0 5547.1 5634.5 5673.4 5784.4 5788.8 4786.5 4806.9 4836.7 4862.8 4876.6 5083.5 5090.2 5095.1 5101.8 5106.6 5035.4 5151.9 5253.0 5254.9 5262.4 5600.9 5642.9 5644.0 5695.6 5733.0 5229.9 5252.2 5264.9 5277.7 5289.0 5633.6 5676.0 5677.1 5687.4 5720.9 5496.2 5576.2 5577.6 5589.5 5766.9 5445.2 5480.7 5486.4 5490.1 5582.0 5549.9 5585.9 5589.1 5606.7 5646.0 5472.0 5476.6 5496.3 5505.6 5517.5 5413.1 5494.6 5609.2 5609.8 5649.4 5295.4 5301.0 5345.6 5358.3 5359.2 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#1] ext.html Cycles:180 Average:30262.54 Median:2939.49 stddev:40841.63 (1389.4%) stddev-sans-first:40895.59 16:08:54 INFO - PID 3532 | Values: 632.6 680.3 693.9 1174.4 1199.5 34203.1 34806.5 34901.7 35253.5 35282.3 32991.0 33002.5 33129.3 33152.8 33293.7 6245.1 6458.1 6597.8 6601.7 6604.6 6564.5 6654.0 6667.3 6669.2 6682.9 6644.6 6677.1 6727.6 6728.8 6736.0 6622.9 6655.2 6660.5 6664.5 6670.4 6587.2 6660.4 6688.8 6697.5 6704.5 31356.5 31662.4 31703.5 31777.2 31837.5 2918.2 2924.3 2933.8 2937.4 2941.6 24928.6 24994.8 25050.0 25111.7 25144.6 2841.1 2851.1 2855.2 2860.1 2863.7 141861.0 141999.0 142114.0 147693.3 152545.2 38796.6 38918.2 38933.6 39086.8 39118.6 41003.0 41107.8 41191.4 41264.4 43370.8 12767.6 12880.6 12899.8 12934.7 12949.6 10214.1 10226.6 10264.7 10268.5 10330.9 12677.4 12799.7 12841.9 12886.2 12921.0 5230.8 5376.6 5382.0 5411.7 5486.6 854.6 892.8 897.5 905.1 920.3 19567.6 20287.0 20472.5 20596.7 21060.3 968.4 970.3 986.0 1001.5 1001.6 10953.7 10991.5 11104.8 11220.6 11348.9 167490.0 169050.0 169064.0 169086.2 169418.2 39858.0 39962.6 40133.0 40157.6 40176.8 1155.5 1189.3 1197.6 1204.1 1220.3 19134.8 19818.0 20645.2 20697.0 20896.3 29188.0 35169.3 36993.8 37086.8 37250.3 158896.0 159883.0 160481.0 160526.0 160656.0 29075.1 29269.1 29487.0 29572.0 29605.6 34599.3 34880.5 35308.5 35588.5 35692.1 26916.5 26962.8 27681.7 27697.4 28144.6 26555.2 27309.9 27387.2 27481.9 28086.2 30360.5 30399.5 30399.7 30469.7 30527.7 26313.9 26452.7 26692.6 27076.5 27950.2 21174.8 22084.7 22128.8 22287.6 22434.6 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#2] jquery.html Cycles:180 Average:24299.20 Median:38231.17 stddev:58559.38 (153.2%) stddev-sans-first:58696.22 16:08:54 INFO - PID 3532 | Values: 422.7 424.2 427.0 437.8 452.5 16896.0 16929.7 17656.9 17699.5 17771.7 17510.6 17771.2 18172.9 18225.3 18500.2 5052.7 5267.4 5277.4 5380.0 5553.1 5309.2 5385.8 5386.2 5389.8 5407.8 5353.5 5357.4 5414.2 5437.3 5439.0 5311.7 5377.8 5396.1 5418.6 5418.8 4724.8 4851.0 4854.5 4855.7 4887.7 21995.0 22194.9 22387.8 22451.0 22467.3 1496.6 1542.2 1550.2 1564.9 1565.2 14804.3 14812.5 14932.8 15012.9 15335.4 2071.6 2100.8 2115.7 2126.9 2148.2 80703.6 86345.6 86410.0 93750.0 96463.5 10623.6 11095.4 11148.7 11229.7 11329.0 15838.4 15949.1 16022.9 16035.8 16087.3 7391.2 7648.3 7684.9 7762.1 7851.7 6733.7 6818.3 6842.0 6928.0 7062.7 6893.2 6990.7 7027.9 7061.9 7099.9 1950.4 1962.4 1965.2 1987.7 2083.2 764.8 823.2 826.5 833.2 838.2 16055.4 16367.6 16484.8 16508.5 17170.5 835.7 840.8 846.4 851.0 867.0 8678.3 8728.6 9055.4 9204.3 9328.7 342919.0 348016.0 352514.0 354544.0 356016.0 73823.6 75022.0 76389.0 77511.6 77766.2 1060.8 1080.3 1112.8 1117.4 1119.1 17278.8 17374.2 17786.6 18322.5 18487.0 37758.8 37836.8 38000.5 38461.8 38717.4 47137.5 47386.5 47441.8 47515.5 49676.0 8242.0 8263.6 8561.7 8738.7 8905.6 13062.9 13235.9 13300.5 13327.1 13605.7 10427.6 10493.4 10848.9 10978.1 11310.6 9233.4 9382.9 10221.2 10645.1 10758.5 10628.9 10884.1 11060.9 11193.3 11345.3 8506.0 8635.5 8713.0 8758.4 8902.2 10647.6 10717.2 10864.9 10940.2 11153.2 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#3] mootools.html Cycles:180 Average:6288.73 Median:3619.25 stddev:3980.32 (110.0%) stddev-sans-first:3973.14 16:08:54 INFO - PID 3532 | Values: 1202.8 1252.7 1267.0 1269.9 1284.6 9797.4 10081.9 10231.7 10260.8 10589.8 10456.4 10596.8 10658.1 10661.0 10666.0 2268.8 2277.6 2296.9 2320.5 2321.0 2097.0 2153.7 2161.9 2169.2 2169.7 2168.6 2210.6 2216.9 2217.8 2238.3 2086.5 2110.6 2128.4 2129.6 2137.8 2124.7 2162.1 2183.7 2227.8 2239.6 10215.5 10267.8 10289.0 10307.6 10316.5 5531.7 5687.7 5693.7 5739.9 5751.7 3263.9 3473.0 3544.4 3546.0 3552.1 1045.4 1069.3 1086.8 1109.0 1120.0 14377.9 14385.0 14414.5 14487.1 14492.7 4763.0 4909.4 4941.4 5010.2 5035.0 10617.4 10628.1 10660.1 10671.6 10672.5 5625.9 5642.0 5656.9 5694.3 5708.1 5155.9 5257.3 5276.0 5278.3 5308.2 3788.4 3939.5 3977.5 4045.0 4046.7 1168.8 1187.1 1189.1 1196.5 1196.5 699.1 724.1 728.8 733.8 741.4 5565.8 5568.7 5642.7 5662.2 5674.8 544.8 563.8 575.6 576.3 580.5 3510.5 3542.4 3686.4 3831.5 3928.3 12363.0 13137.3 13255.7 13318.5 13390.6 12623.8 12973.8 13013.8 13039.4 13163.7 7344.3 7395.4 7450.5 7466.2 7478.7 6017.1 6153.3 6172.9 6255.7 6292.8 11013.7 11467.5 11515.1 11694.4 11718.0 11719.9 11895.3 11963.3 12133.6 12172.5 9347.3 9669.6 9683.0 9698.4 9709.5 9599.0 9602.4 9655.0 9668.2 9693.3 7893.4 7928.1 7990.3 8065.5 8187.5 7657.8 7735.6 7816.5 7916.6 8000.7 7080.2 7095.5 7117.8 7131.6 7142.9 5279.8 5334.1 5379.9 5384.2 5401.5 5348.6 5415.3 5511.9 5517.6 5520.4 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#4] prototype.html Cycles:180 Average:4786.35 Median:5405.00 stddev:1382.33 (25.6%) stddev-sans-first:1345.73 16:08:54 INFO - PID 3532 | Values: 362.6 383.1 387.2 392.1 392.1 5475.2 5500.8 5506.4 5510.4 5594.0 5361.9 5381.2 5396.7 5399.9 5403.0 4213.0 4270.9 4282.4 4283.4 4295.2 4268.6 4283.1 4285.2 4286.8 4296.8 4248.6 4254.9 4264.1 4278.3 4282.9 4169.5 4235.5 4279.6 4283.8 4283.9 3842.0 3859.0 3862.3 3868.0 3873.1 5322.8 5341.7 5341.9 5357.9 5361.2 4951.5 5011.8 5037.4 5055.0 5075.8 5673.6 5725.3 5740.1 5762.7 5765.8 4326.5 4339.0 4346.8 4353.2 4362.5 5948.5 6001.8 6028.6 6058.1 6109.9 4394.0 4404.4 4434.4 4436.8 4444.5 4469.0 4499.7 4520.7 4548.5 4552.2 4578.3 4759.8 4773.8 4780.6 5037.7 5743.5 5745.8 5759.2 5761.8 5789.3 1412.2 1435.8 1439.3 1439.5 1447.8 940.3 943.0 951.6 960.9 966.7 4893.8 4898.3 4899.3 4899.8 4908.1 5891.6 5927.3 5941.6 5950.9 5974.9 4841.2 4858.6 4907.6 4943.5 4943.8 3119.3 3128.9 3153.1 3157.4 3157.4 5356.7 5407.0 5441.5 5444.1 5463.0 5899.7 6078.4 6119.7 6155.1 6158.1 5442.2 5470.8 5471.2 5492.7 5494.3 5901.3 5924.3 5931.2 5936.7 5957.7 5690.6 5808.5 5859.7 5884.3 5890.5 5433.5 5438.4 5438.6 5465.6 5471.9 3958.3 4007.8 4013.2 4013.9 4024.1 5964.0 5986.3 5992.3 6005.7 6034.6 5765.6 5795.3 5821.3 5843.1 5845.0 5490.1 5727.9 5761.0 5762.3 5818.4 5649.7 5672.8 5703.6 5705.8 5741.4 5623.7 5769.0 5841.6 5853.3 5857.4 5647.4 5656.1 5657.1 5659.0 5681.9 16:08:54 INFO - PID 3532 | 16:08:54 INFO - PID 3532 | [#5] yui.html Cycles:180 Average:15064.96 Median:3752.76 stddev:17977.63 (479.1%) stddev-sans-first:17995.64 16:08:54 INFO - PID 3532 | Values: 688.5 692.4 701.3 708.5 708.8 12591.0 13158.9 13169.0 15404.0 16879.7 13852.3 13947.3 14056.0 14160.0 16126.7 855.7 865.7 868.8 869.4 870.4 850.6 858.6 870.3 870.7 876.4 891.3 902.7 903.2 906.1 906.8 906.2 910.7 912.7 913.2 914.3 666.6 669.8 672.1 672.5 675.7 14748.4 14772.3 14949.1 15020.1 15236.8 13663.7 13768.7 14000.8 14102.3 14127.9 11792.1 12021.0 12041.6 12057.8 12142.5 2867.0 2938.3 2947.1 2949.7 2953.4 69361.3 69981.0 70091.0 70736.6 71459.0 12277.6 12286.3 12455.1 12499.4 12590.5 20730.3 22005.6 22276.6 22434.9 22525.0 5996.4 6024.4 6112.4 6126.8 6176.1 2924.6 3015.1 3016.1 3019.9 3020.8 3497.1 3568.1 3582.1 3587.7 3653.6 731.8 744.6 746.4 748.5 749.9 581.2 595.3 595.9 602.6 619.2 9237.3 9499.6 10066.2 10078.5 10258.9 548.9 612.1 614.3 614.7 620.3 3851.9 4050.5 4055.6 4197.3 4219.9 61604.4 61910.0 62276.0 62409.4 62458.4 53605.7 53804.0 54287.1 54360.7 54657.0 48286.0 48716.0 50122.2 50459.0 50653.7 7800.1 8123.2 10186.8 10913.1 11007.4 24879.8 24905.9 25025.9 25446.0 25969.2 41443.4 42899.8 43592.0 43820.0 45560.8 13339.4 13583.9 13801.6 13827.0 14011.9 14395.1 14804.6 14804.9 14911.9 15067.0 12708.4 12772.0 12791.4 12819.7 12825.2 12678.2 12795.3 12898.2 12997.9 13139.7 12775.4 13243.9 13316.1 13447.8 13470.9 9234.5 9261.6 9376.4 9570.9 9957.1 10183.4 10254.9 10627.9 10720.1 10797.1 16:08:54 INFO - PID 3532 | -------- Summary: end -------- 16:08:54 INFO - PID 3532 | 16:08:54 INFO - TEST-INFO | 3532: exit 0 16:08:54 INFO - TEST-OK | dromaeo_css | took 1149400ms 16:08:54 INFO - TEST-START | kraken 16:08:54 INFO - operating with platform_type : linux_ 16:08:54 INFO - Initialising browser for kraken test... 16:08:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41066/getInfo.html -profile /tmp/tmpKayVkQ/profile 16:08:54 INFO - TEST-INFO | started process 3924 (/builds/slave/test/build/application/firefox/firefox http://localhost:41066/getInfo.html) 16:09:29 INFO - TEST-INFO | 3924: killed by SIGTERM 16:09:30 INFO - Browser initialized. 16:09:30 INFO - Running cycle 1/1 for kraken test... 16:09:30 INFO - TEST-INFO | started process 4122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKayVkQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:42 INFO - PID 4122 | RSS: Main: 174243840 16:09:42 INFO - PID 4122 | 16:09:44 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41066/tests/kraken/driver.html?testName=audio-beat-detection) 16:09:45 INFO - PID 4122 | RSS: Main: 180297728 16:09:45 INFO - PID 4122 | 16:09:48 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41066/tests/kraken/driver.html?testName=audio-dft) 16:09:48 INFO - PID 4122 | RSS: Main: 179261440 16:09:48 INFO - PID 4122 | 16:09:51 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41066/tests/kraken/driver.html?testName=audio-fft) 16:09:52 INFO - PID 4122 | RSS: Main: 176865280 16:09:52 INFO - PID 4122 | 16:09:54 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41066/tests/kraken/driver.html?testName=audio-oscillator) 16:09:55 INFO - PID 4122 | RSS: Main: 177299456 16:09:55 INFO - PID 4122 | 16:09:57 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41066/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:09:57 INFO - PID 4122 | RSS: Main: 177381376 16:09:57 INFO - PID 4122 | 16:10:01 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41066/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:01 INFO - PID 4122 | RSS: Main: 178253824 16:10:01 INFO - PID 4122 | 16:10:05 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41066/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:05 INFO - PID 4122 | RSS: Main: 177836032 16:10:05 INFO - PID 4122 | 16:10:08 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41066/tests/kraken/driver.html?testName=json-parse-financial) 16:10:09 INFO - PID 4122 | RSS: Main: 178302976 16:10:09 INFO - PID 4122 | 16:10:10 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41066/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:11 INFO - PID 4122 | RSS: Main: 178458624 16:10:11 INFO - PID 4122 | 16:10:13 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:14 INFO - PID 4122 | RSS: Main: 178262016 16:10:14 INFO - PID 4122 | 16:10:16 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:17 INFO - PID 4122 | RSS: Main: 178388992 16:10:17 INFO - PID 4122 | 16:10:19 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:20 INFO - PID 4122 | RSS: Main: 178507776 16:10:20 INFO - PID 4122 | 16:10:23 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:24 INFO - PID 4122 | RSS: Main: 178503680 16:10:24 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | Cycle 1(1): loaded http://localhost:41066/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41066/tests/kraken/driver.html?testName=ai-astar) 16:10:26 INFO - PID 4122 | RSS: Main: 179777536 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | __start_tp_report 16:10:26 INFO - PID 4122 | _x_x_mozilla_page_load 16:10:26 INFO - PID 4122 | _x_x_mozilla_page_load_details 16:10:26 INFO - PID 4122 | |i|pagename|runs| 16:10:26 INFO - PID 4122 | |0;ai-astar;105;113;112;112;112;112;113;112;113;114 16:10:26 INFO - PID 4122 | |1;audio-beat-detection;148;124;130;178;130;129;122;129;146;125 16:10:26 INFO - PID 4122 | |2;audio-dft;166;163;162;166;171;168;167;164;164;170 16:10:26 INFO - PID 4122 | |3;audio-fft;74;81;76;108;74;73;74;74;90;75 16:10:26 INFO - PID 4122 | |4;audio-oscillator;84;83;106;84;85;83;97;83;87;106 16:10:26 INFO - PID 4122 | |5;imaging-gaussian-blur;115;115;114;114;114;114;114;113;114;114 16:10:26 INFO - PID 4122 | |6;imaging-darkroom;151;153;153;151;151;152;151;151;150;151 16:10:26 INFO - PID 4122 | |7;imaging-desaturate;94;95;95;94;94;94;94;95;94;94 16:10:26 INFO - PID 4122 | |8;json-parse-financial;55;56;57;53;55;56;56;57;55;57 16:10:26 INFO - PID 4122 | |9;json-stringify-tinderbox;56;56;57;57;58;59;57;57;57;56 16:10:26 INFO - PID 4122 | |10;stanford-crypto-aes;89;97;97;87;88;88;90;98;123;86 16:10:26 INFO - PID 4122 | |11;stanford-crypto-ccm;134;143;134;133;132;138;139;137;139;131 16:10:26 INFO - PID 4122 | |12;stanford-crypto-pbkdf2;189;198;200;201;197;189;196;215;197;210 16:10:26 INFO - PID 4122 | |13;stanford-crypto-sha256-iterative;73;79;73;74;72;71;74;74;73;77 16:10:26 INFO - PID 4122 | __end_tp_report 16:10:26 INFO - PID 4122 | __start_cc_report 16:10:26 INFO - PID 4122 | _x_x_mozilla_cycle_collect,914 16:10:26 INFO - PID 4122 | __end_cc_report 16:10:26 INFO - PID 4122 | __startTimestamp1495581026376__endTimestamp 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | ------- Summary: start ------- 16:10:26 INFO - PID 4122 | Number of tests: 14 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.50 stddev:2.49 (2.2%) stddev-sans-first:0.73 16:10:26 INFO - PID 4122 | Values: 105.0 113.0 112.0 112.0 112.0 112.0 113.0 112.0 113.0 114.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:130.00 stddev:17.12 (13.2%) stddev-sans-first:17.61 16:10:26 INFO - PID 4122 | Values: 148.0 124.0 130.0 178.0 130.0 129.0 122.0 129.0 146.0 125.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#2] audio-dft Cycles:10 Average:166.10 Median:166.50 stddev:2.96 (1.8%) stddev-sans-first:3.14 16:10:26 INFO - PID 4122 | Values: 166.0 163.0 162.0 166.0 171.0 168.0 167.0 164.0 164.0 170.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#3] audio-fft Cycles:10 Average:79.90 Median:74.50 stddev:11.15 (15.0%) stddev-sans-first:11.62 16:10:26 INFO - PID 4122 | Values: 74.0 81.0 76.0 108.0 74.0 73.0 74.0 74.0 90.0 75.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:84.00 stddev:9.51 (11.3%) stddev-sans-first:9.85 16:10:26 INFO - PID 4122 | Values: 84.0 83.0 106.0 84.0 85.0 83.0 97.0 83.0 87.0 106.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 16:10:26 INFO - PID 4122 | Values: 115.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 16:10:26 INFO - PID 4122 | Values: 151.0 153.0 153.0 151.0 151.0 152.0 151.0 151.0 150.0 151.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:10:26 INFO - PID 4122 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.25 (2.2%) stddev-sans-first:1.30 16:10:26 INFO - PID 4122 | Values: 55.0 56.0 57.0 53.0 55.0 56.0 56.0 57.0 55.0 57.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 16:10:26 INFO - PID 4122 | Values: 56.0 56.0 57.0 57.0 58.0 59.0 57.0 57.0 57.0 56.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:89.50 stddev:11.06 (12.4%) stddev-sans-first:11.56 16:10:26 INFO - PID 4122 | Values: 89.0 97.0 97.0 87.0 88.0 88.0 90.0 98.0 123.0 86.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:137.50 stddev:3.80 (2.8%) stddev-sans-first:3.96 16:10:26 INFO - PID 4122 | Values: 134.0 143.0 134.0 133.0 132.0 138.0 139.0 137.0 139.0 131.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.20 Median:199.00 stddev:8.16 (4.1%) stddev-sans-first:7.78 16:10:26 INFO - PID 4122 | Values: 189.0 198.0 200.0 201.0 197.0 189.0 196.0 215.0 197.0 210.0 16:10:26 INFO - PID 4122 | 16:10:26 INFO - PID 4122 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:74.00 stddev:2.36 (3.2%) stddev-sans-first:2.47 16:10:26 INFO - PID 4122 | Values: 73.0 79.0 73.0 74.0 72.0 71.0 74.0 74.0 73.0 77.0 16:10:26 INFO - PID 4122 | -------- Summary: end -------- 16:10:26 INFO - PID 4122 | 16:10:27 INFO - TEST-INFO | 4122: exit 0 16:10:27 INFO - TEST-OK | kraken | took 92567ms 16:10:27 INFO - SUITE-END | took 1241s 16:10:28 INFO - Completed test suite (00:20:42) 16:10:28 INFO - javascript benchmark 16:10:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6678.397743757665, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2678.2370769069457, 2683.2084535062154, 2695.2992222022267, 2721.4829182455337, 2726.822756520826, 5147.948520514794, 5194.610404219689, 5222.086134926382, 5235.031519168956, 5261.316028916235, 5191.948080519193, 5248.0, 5257.1851363038695, 5334.839954801362, 5356.785728570852, 4671.252599584067, 4702.24764037754, 4710.057988402316, 4734.289856521515, 4778.78495467703, 4709.929351059737, 4717.268823332389, 4793.3528973588645, 4801.831935882245, 4851.126797176506, 4564.863054108382, 4599.218132917378, 4719.244920812654, 4726.621870250372, 4776.283557466373, 4688.671792974493, 4727.959848833251, 4740.715557066553, 4755.453122890843, 4759.571638552512, 4692.249240121564, 4725.66920315578, 4733.053389322149, 4733.692309999839, 4743.596794272491, 5218.504242096995, 5263.105272103751, 5269.578433725293, 5291.28567643367, 5300.867478313035, 5543.196236545718, 5596.524295434893, 5627.8593035174445, 5693.459121383462, 5728.79949201776, 5671.886562268732, 5694.857628559278, 5699.458551437607, 5705.743241554099, 5708.429157084258, 5252.606054545924, 5257.369115706063, 5258.947410525922, 5262.947370526323, 5266.15741481365, 5606.635568688022, 5776.740046697824, 5804.303483581997, 5808.88382232353, 5856.355800861902, 5452.127659574449, 5508.862278443071, 5589.217509548666, 5605.607607467438, 5610.831675049755, 5624.015797235549, 5632.155176723524, 5633.633813802172, 5648.350439699405, 5666.546676265889, 5605.0, 5613.831585052455, 5614.326280846324, 5636.520895723827, 5664.2636457261115, 5482.890342193134, 5542.0, 5573.191887176336, 5579.9163012554845, 5714.828555143353, 4759.190937540627, 4781.235002399669, 4782.33047373368, 4789.233722604366, 4797.544233297831, 4030.5969403059457, 4039.818208180639, 4042.838286468508, 4043.3732771420478, 4089.1003979124544, 4753.952460475421, 4776.09254241693, 4785.353977213032, 4802.159622066124, 4822.035592881438, 5547.057000309957, 5634.549236061187, 5673.404292549306, 5784.3926387729525, 5788.82633520995, 4786.497417771154, 4806.8798280043275, 4836.709797412166, 4862.805487780449, 4876.634252431011, 5083.466236045236, 5090.185570308733, 5095.0574143783515, 5101.795928162906, 5106.565941894906, 5035.446100928822, 5151.922721159111, 5253.028189784902, 5254.894902102013, 5262.368515778131, 5600.915986260128, 5642.887142257214, 5644.0, 5695.572832037531, 5732.971335143465, 5229.947700522946, 5252.185911183773, 5264.894702106013, 5277.683338999673, 5288.9735551322, 5633.63381380209, 5675.971620141873, 5677.063284558167, 5687.374388817144, 5720.914186287292, 5496.1755736639825, 5576.247206627054, 5577.637453565505, 5589.469000444951, 5766.913496297475, 5445.1832225166545, 5480.671159730429, 5486.369067557282, 5490.0941344677685, 5582.0, 5549.944500554942, 5585.916211256916, 5589.105743081088, 5606.6916319602815, 5645.971770141287, 5472.0, 5476.561875050066, 5496.285482887359, 5505.614606977473, 5517.503424691797, 5413.133898576209, 5494.615376923577, 5609.242752228397, 5609.775608975759, 5649.406812284651, 5295.36455625312, 5301.0, 5345.572354211576, 5358.33020872391, 5359.249705041219], "value": 5136.398510162822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.6204277433537, 680.2924958043633, 693.9340762627552, 1174.4421399835073, 1199.478226971267, 34203.144921376945, 34806.477902831415, 34901.65098349016, 35253.47119793199, 35282.294354112935, 32991.0, 33002.504962425584, 33129.33741325172, 33152.83423582885, 33293.66706332936, 6245.09446130312, 6458.063580780784, 6597.80206593803, 6601.7359305627715, 6604.57070290432, 6564.474842012628, 6654.0, 6667.266600673922, 6669.166354205724, 6682.93317066828, 6644.568103073285, 6677.131972843524, 6727.562708423961, 6728.764493242737, 6735.9663201683925, 6622.93377066228, 6655.201375834882, 6660.50046246531, 6664.533482656216, 6670.366315200048, 6587.2095348558005, 6660.367265109807, 6688.83277918051, 6697.464202863759, 6704.463642908604, 31356.529652055237, 31662.366752664817, 31703.52444713331, 31777.205569860707, 31837.52243716346, 2918.2266699324696, 2924.3274046969104, 2933.8239705617516, 2937.412517496488, 2941.5734718465915, 24928.626070608956, 24994.750052499425, 25050.0, 25111.748882511307, 25144.622830657554, 2841.105051908642, 2851.1019029006006, 2855.2433605094666, 2860.113364856901, 2863.656361236644, 141861.0, 141999.0, 142114.0, 147693.2615336927, 152545.23727381404, 38796.6120338793, 38918.22163556713, 38933.61066389357, 39086.804565976985, 39118.60881391207, 41003.0, 41107.7944610275, 41191.38212926809, 41264.38103428451, 43370.7831460847, 12767.61697149087, 12880.613581592566, 12899.806502902464, 12934.741305174031, 12949.611511654666, 10214.08073273394, 10226.6420675276, 10264.692059238234, 10268.486575671186, 10330.896691033144, 12677.429515671816, 12799.744005119845, 12841.871581284255, 12886.226826390446, 12921.0, 5230.790768369298, 5376.569874410116, 5381.977424289373, 5411.702356370359, 5486.561075114061, 854.5556310718391, 892.834825557274, 897.4794619120894, 905.063259526387, 920.300571565615, 19567.608647826964, 20287.0, 20472.488187795425, 20596.691049634268, 21060.26289079875, 968.4334664221368, 970.2916870684439, 985.9852102218473, 1001.5242759689235, 1001.5643195210107, 10953.726156846142, 10991.505382257657, 11104.833427498594, 11220.551177952953, 11348.886511134948, 167490.0, 169050.0, 169064.0, 169086.15456922638, 169418.15290923466, 39858.0, 39962.60037399647, 40133.0, 40157.598424015385, 40176.79911600423, 1155.4684844971239, 1189.2864281431073, 1197.5748609243767, 1204.084895479442, 1220.3288191494642, 19134.80865191386, 19818.0, 20645.17419303211, 20697.0, 20896.268630598464, 29188.0, 35169.29661406809, 36993.81503092467, 37086.814565927, 37250.254994899406, 158896.0, 159883.0, 160481.0, 160526.0, 160656.0, 29075.127746167647, 29269.121926342243, 29487.0, 29572.0, 29605.5559166617, 34599.30801383908, 34880.47679284863, 35308.47037294494, 35588.466173007946, 35692.10769730777, 26916.461670766083, 26962.75415342236, 27681.72318276872, 27697.446051079267, 28144.577831332135, 26555.203343899713, 27309.86345068262, 27387.178384648494, 27481.862590687717, 28086.157415277576, 30360.54459183158, 30399.54400683947, 30399.69600304057, 30469.695303047574, 30527.694723052486, 26313.868430657723, 26452.73547264503, 26692.59961100624, 27076.45847083008, 27950.16149515518, 21174.788252117898, 22084.668729968744, 22128.778712212672, 22287.554248914606, 22434.551308974056], "value": 14381.539711692047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.6944156843375, 424.1559296998973, 426.9893252668682, 437.79642466253193, 452.49998751379746, 16895.986240825543, 16929.746053809187, 17656.911715441438, 17699.46901592951, 17771.733423998652, 17510.599152067836, 17771.20029598668, 18172.909135454338, 18225.270989160417, 18500.16749246284, 5052.747362631872, 5267.446918073604, 5277.366715994086, 5380.0, 5553.111502159655, 5309.230161626581, 5385.7576409061685, 5386.218998245252, 5389.8113566025195, 5407.783688652469, 5353.518183363496, 5357.4106848246665, 5414.242006119146, 5437.32033495813, 5439.0209762242785, 5311.725185955363, 5377.784888604451, 5396.082665946798, 5418.566514678816, 5418.810341638064, 4724.76376181193, 4851.02979404117, 4854.514548545152, 4855.732934688591, 4887.706737595756, 21995.0, 22194.88902555477, 22387.77612223873, 22451.0, 22467.32598022062, 1496.6408062065134, 1542.1518165009313, 1550.178405445116, 1564.9295781689736, 1565.2173913043478, 14804.333804978694, 14812.481563145346, 14932.776008359884, 15012.924935375362, 15335.386584536605, 2071.59603877244, 2100.789921007902, 2115.724955755742, 2126.946826329339, 2148.1729534129317, 80703.59648201721, 86345.56827215824, 86410.0, 93750.0, 96463.51768241184, 10623.628173013907, 11095.44522773858, 11148.721281968015, 11229.71925701864, 11329.0, 15838.366465341258, 15949.122798245975, 16022.919885400732, 16035.839641603434, 16087.276072576527, 7391.15001774792, 7648.34989025939, 7684.884726729104, 7762.146163921964, 7851.725189618336, 6733.663316834139, 6818.28408017161, 6842.0, 6927.965360173167, 7062.717491300393, 6893.20728116263, 6990.685419156151, 7027.929720702829, 7061.858762824818, 7099.89350159748, 1950.3563823938066, 1962.3720409468833, 1965.164804957887, 1987.731656226391, 2083.2083808152806, 764.75910088322, 823.1644880446352, 826.4504104770381, 833.1959658929162, 838.1702114906199, 16055.357785688675, 16367.59081022984, 16484.752728709078, 16508.504744857673, 17170.484885453207, 835.7492752174323, 840.80549765113, 846.3821410370464, 851.0, 867.0159369115972, 8678.305735541267, 8728.607212675444, 9055.411398259092, 9204.263658907272, 9328.720138395858, 342919.0, 348016.0, 352514.0, 354544.0, 356016.0, 73823.63088184631, 75022.0, 76388.9999999989, 77511.61244193742, 77766.2223377759, 1060.8090543702053, 1080.33559360994, 1112.7996960547018, 1117.3742704085737, 1119.0655802405083, 17278.827211727723, 17374.218160183074, 17786.644267114843, 18322.450326490227, 18487.0, 37758.81120594379, 37836.81081594574, 38000.52996555215, 38461.80769096249, 38717.419238712006, 47137.52862471469, 47386.52613473821, 47441.762791187204, 47515.524844752494, 49676.00647987092, 8241.95879020601, 8263.586820659064, 8561.700340488302, 8738.69414570487, 8905.55472226373, 13062.934685326512, 13235.867641323464, 13300.46798128064, 13327.067105302924, 13605.65985850322, 10427.582896684047, 10493.422861742372, 10848.945755271174, 10978.121750259801, 11310.60412885545, 9233.399829011092, 9382.859257111013, 10221.182305415401, 10645.0951669109, 10758.46207689628, 10628.89371106279, 10884.129269658566, 11060.944695276472, 11193.27243729155, 11345.319280843176, 8506.0, 8635.481871087755, 8712.954445466585, 8758.386912916034, 8902.198802107841, 10647.627333043307, 10717.249792514762, 10864.891351086704, 10940.179486538382, 11153.219274650697], "value": 8766.461628069741, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1202.7895118354288, 1252.7494501099782, 1267.0, 1269.8793614606616, 1284.5504073574248, 9797.363171393863, 10081.94959025204, 10231.693049208527, 10260.846087308688, 10589.84115238272, 10456.4248966307, 10596.841047384296, 10658.147348212144, 10661.0, 10666.0, 2268.8411811173182, 2277.5786479501344, 2296.8966396512196, 2320.5010922651627, 2320.9767902321014, 2097.0, 2153.7307836520436, 2161.91352345906, 2169.240765731997, 2169.652855543113, 2168.631332673441, 2210.557888422314, 2216.944576385587, 2217.7671344508835, 2238.2613737466677, 2086.457521044532, 2110.6095372356085, 2128.4040468668795, 2129.6060228857555, 2137.8289736821016, 2124.702541644171, 2162.135145941636, 2183.7379514458207, 2227.7660845611304, 2239.55208958209, 10215.489225538693, 10267.845982310271, 10289.0, 10307.639232626898, 10316.484175791256, 5531.668099913978, 5687.7156142193135, 5693.715314234313, 5739.913901291484, 5751.712414379264, 3263.9347213055844, 3473.0, 3544.3620148373284, 3546.0248431681234, 3552.0942159749357, 1045.3571053801934, 1069.2675517270695, 1086.8478413022183, 1108.983365249522, 1119.9552017919273, 14377.856221437756, 14385.0, 14414.495492657812, 14487.058341207789, 14492.710145797026, 4762.9523704763205, 4909.386326709161, 4941.407031156285, 5010.248462730619, 5034.974825125851, 10617.416042117758, 10628.09661178793, 10660.147188224923, 10671.573137074585, 10672.519736611872, 5625.8593535161945, 5641.984442800335, 5656.85857853557, 5694.259746232963, 5708.058170401837, 5155.948440515622, 5257.316548848626, 5276.0, 5278.261043453919, 5308.177232528964, 3788.3559794834946, 3939.468171796828, 3977.5028121484816, 4044.988752811797, 4046.736962097454, 1168.7662467506532, 1187.145255416099, 1189.108168873345, 1196.4634973855482, 1196.5154112584416, 699.1260923845193, 724.051492544768, 728.8068661804626, 733.8018734941643, 741.4142827166586, 5565.777368905198, 5568.6658800472105, 5642.746076426571, 5662.150677398423, 5674.808290259092, 544.7548603128608, 563.760401829221, 575.6488541989384, 576.2911618708928, 580.5181699189662, 3510.4909788081095, 3542.3977923753023, 3686.4101743721412, 3831.501904752364, 3928.3321835287497, 12363.0, 13137.34313284332, 13255.73488530224, 13318.467261309439, 13390.598282051555, 12623.810642840364, 12973.767492088236, 13013.804792928113, 13039.413226404835, 13163.736725265633, 7344.265573442613, 7395.4083673306, 7450.478466507292, 7466.2160473150625, 7478.663460144307, 6017.127516510169, 6153.261608606996, 6172.938270617416, 6255.655938923221, 6292.811215663537, 11013.724656883322, 11467.483963221508, 11515.136364772508, 11694.415279236175, 11718.0, 11719.941400292944, 11895.286282823059, 11963.342016188844, 12133.635990920286, 12172.45223964906, 9347.252219822261, 9669.613215471303, 9682.95158524231, 9698.418094914328, 9709.5145242739, 9599.0, 9602.375845570015, 9654.951725241328, 9668.226541876773, 9693.321467497208, 7893.36853051743, 7928.127905930459, 7990.3208227299, 8065.516069035877, 8187.549684767395, 7657.808554786175, 7735.574543400167, 7816.452848300564, 7916.6041697916025, 8000.719974800854, 7080.221175670775, 7095.46783991213, 7117.82205444868, 7131.572105673677, 7142.892856607259, 5279.762410691451, 5334.093204155381, 5379.919301210407, 5384.246205531151, 5401.4868587484125, 5348.5988550858065, 5415.323084614423, 5511.862203444785, 5517.586181036358, 5520.447955204448], "value": 4717.561237692671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.5848403577903, 383.1111820576264, 387.2081593142025, 392.0727479510957, 392.1177350960339, 5475.178723191523, 5500.834974950748, 5506.394296627373, 5510.448955104487, 5593.972030139854, 5361.919571206426, 5381.246625472427, 5396.730163491819, 5399.919001214985, 5403.0, 4212.967822883398, 4270.893227669302, 4282.421873047146, 4283.421738065368, 4295.226859165349, 4268.615824575788, 4283.121959998193, 4285.164392943378, 4286.828526858922, 4296.849610263642, 4248.596383343577, 4254.87235382939, 4264.104538047014, 4278.25130602145, 4282.935755963647, 4169.520505141917, 4235.538739134993, 4279.636230920375, 4283.785810709468, 4283.915580781848, 3841.9807900960595, 3858.9614103858885, 3862.324093283692, 3867.961320386816, 3873.1479074603826, 5322.760475778599, 5341.732913354355, 5341.946580534222, 5357.892842143136, 5361.169018802092, 4951.504849515056, 5011.799528018875, 5037.420696619863, 5054.974725126388, 5075.796968121271, 5673.602847800656, 5725.341585717654, 5740.13897915316, 5762.683052432115, 5765.769369225226, 4326.545712700153, 4338.978305108486, 4346.782660866944, 4353.216421044211, 4362.498312694049, 5948.524118070587, 6001.789937352171, 6028.608140470856, 6058.091286307045, 6109.877802443971, 4393.956060439355, 4404.392396775154, 4434.385975430014, 4436.7559784211535, 4444.466664000341, 4469.0, 4499.730016199039, 4520.728756274634, 4548.522405147478, 4552.1806074906835, 4578.336141259499, 4759.762011899391, 4773.785179666923, 4780.617550595944, 5037.748112594356, 5743.483086522149, 5745.827625171251, 5759.164921086419, 5761.798337058183, 5789.3052833659385, 1412.1950488221614, 1435.8277006759256, 1439.2515891736448, 1439.4530078570285, 1447.8045463862454, 940.2571968145089, 942.9528523573794, 951.6383774165772, 960.9327347085776, 966.709987003896, 4893.779779909913, 4898.289747986522, 4899.2896030075435, 4899.779509921991, 4908.134908734729, 5891.617044892068, 5927.347991720907, 5941.603213621888, 5950.9107363389585, 5974.940250597525, 4841.235797013222, 4858.623256836536, 4907.607391408679, 4943.5169449165105, 4943.826966056171, 3119.2981579144966, 3128.8748450061744, 3153.1486498645695, 3157.400093982182, 3157.431662300762, 5356.651817631841, 5406.999705054499, 5441.483059109379, 5444.128939369682, 5463.0, 5899.734511946973, 6078.3921607838865, 6119.663418512025, 6155.138280640714, 6158.107074474266, 5442.156465747816, 5470.753816078286, 5471.206675032098, 5492.697901615449, 5494.2857428534035, 5901.29184497863, 5924.348321684697, 5931.1992880960515, 5936.723604425069, 5957.702114894325, 5690.630109042899, 5808.5062769665055, 5859.707014649336, 5884.293884733859, 5890.4698577128265, 5433.4566543345345, 5438.428964958781, 5438.592105592017, 5465.562754979513, 5471.945280547144, 3958.267720471722, 4007.8396864126275, 4013.1973605279645, 4013.899652508594, 4024.0945787197647, 5964.0, 5986.341502434816, 5992.280926288873, 6005.699715014319, 6034.607750496204, 5765.596408251382, 5795.333536643171, 5821.330546987151, 5843.1235314703135, 5844.970775146097, 5490.149026900677, 5727.914081288867, 5760.971195143998, 5762.337331206965, 5818.389069147848, 5649.683629711799, 5672.7730890763905, 5703.572232082527, 5705.771769129188, 5741.397153299011, 5623.690697011703, 5769.048107062287, 5841.561882858888, 5853.2976042875125, 5857.4435428634215, 5647.3787883333625, 5656.095024796013, 5657.12314591239, 5659.0, 5681.914771278517], "value": 4354.902976520198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.5317983771035, 692.4183685704007, 701.3372363116854, 708.4544900426674, 708.7979925721171, 12591.0, 13158.934205328971, 13169.012324075697, 15404.0, 16879.66240675189, 13852.307384630752, 13947.302634868242, 14056.0, 14160.0, 16126.677466450694, 855.7261676263598, 865.7446053414227, 868.7524055644111, 869.4044579463057, 870.3559366069095, 850.5874650794391, 858.5921687198593, 870.316801310971, 870.7344260000697, 876.4040452492303, 891.2914233184633, 902.6840605787954, 903.2412773270485, 906.0667512462174, 906.8413027720155, 906.2387594420685, 910.717677519971, 912.7489940266413, 913.2237598041678, 914.3005600715425, 666.6133642487346, 669.8057563306635, 672.1463741048841, 672.5225090185974, 675.7026908160394, 14748.410063597443, 14772.335244914004, 14949.103053816698, 15020.09879407239, 15236.847631523653, 13663.726725465534, 13768.724625507533, 14000.789988150185, 14102.294885255696, 14127.929360353133, 11792.115591330685, 12021.0, 12041.638750837401, 12057.819132713017, 12142.453589588402, 2867.0108812459875, 2938.30949726814, 2947.101134154082, 2949.6607890092487, 2953.3650265192873, 69361.3063869365, 69981.0, 70090.99090091001, 70736.6463167686, 71459.0, 12277.570285039981, 12286.262824230575, 12455.128141030222, 12499.3750312484, 12590.496380144692, 20730.274440394816, 22005.559888802134, 22276.554468910854, 22434.887825561094, 22525.0, 5996.430339117776, 6024.3975602439405, 6112.449879510777, 6126.754929802847, 6176.135341052256, 2924.5905573219343, 3015.050259168355, 3016.080095570872, 3019.8641061152302, 3020.8489575521135, 3497.143199916037, 3568.1258091767163, 3582.1044738815294, 3587.695045921126, 3653.598104208535, 731.765834932827, 744.6202436757185, 746.4103358346954, 748.472327009457, 749.8950146979428, 581.2037508613226, 595.2529575382866, 595.8510372406898, 602.5601311042875, 619.1641284266294, 9237.30720195988, 9499.572519236652, 10066.245031622657, 10078.496075196212, 10258.948705256425, 548.8957098151418, 612.0574315553997, 614.2567493333117, 614.7080136935009, 620.3114542857425, 3851.9229615407535, 4050.4936882889638, 4055.6349928506575, 4197.3074442717225, 4219.936700949488, 61604.38395616076, 61910.0, 62276.0, 62409.375906240355, 62458.37541624616, 53605.73197133989, 53804.0, 54287.09995150151, 54360.72819635877, 54657.0, 48286.0, 48715.999999999294, 50122.24816627753, 50459.0, 50653.746731266845, 7800.063992320957, 8123.228293312126, 10186.796264074825, 10913.126949844152, 11007.394593297446, 24879.751202487743, 24905.87547062253, 25025.874870625532, 25446.0, 25969.22092337233, 41443.37834932539, 42899.78550107229, 43592.0, 43820.0, 45560.77219613881, 13339.399727012114, 13583.932080339535, 13801.585952421443, 13827.0, 14011.859881401464, 14395.136291822524, 14804.555863324118, 14804.925975370053, 14911.850881491046, 15067.0, 12708.428120734405, 12772.0, 12791.42438590247, 12819.679508012001, 12825.166364186298, 12678.239305641691, 12795.296258705488, 12898.226106433644, 12997.870021299666, 13139.671508211988, 12775.425105870072, 13243.933780331037, 13316.134451260637, 13447.798283025959, 13470.93264533671, 9234.538273086184, 9261.629534818532, 9376.3905346155, 9570.856437153587, 9957.0540798624, 10183.439910804977, 10254.897451025394, 10627.893721063, 10720.088792452729, 10797.082248009006], "value": 6668.347226789055, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 113.0, 114.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 124.0, 130.0, 178.0, 130.0, 129.0, 122.0, 129.0, 146.0, 125.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 162.0, 166.0, 171.0, 168.0, 167.0, 164.0, 164.0, 170.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 76.0, 108.0, 74.0, 73.0, 74.0, 74.0, 90.0, 75.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 106.0, 84.0, 85.0, 83.0, 97.0, 83.0, 87.0, 106.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 153.0, 151.0, 151.0, 152.0, 151.0, 151.0, 150.0, 151.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 53.0, 55.0, 56.0, 56.0, 57.0, 55.0, 57.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 57.0, 58.0, 59.0, 57.0, 57.0, 57.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 97.0, 97.0, 87.0, 88.0, 88.0, 90.0, 98.0, 123.0, 86.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 143.0, 134.0, 133.0, 132.0, 138.0, 139.0, 137.0, 139.0, 131.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 198.0, 200.0, 201.0, 197.0, 189.0, 196.0, 215.0, 197.0, 210.0], "value": 199.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 73.0, 74.0, 72.0, 71.0, 74.0, 74.0, 73.0, 77.0], "value": 74.0, "unit": "score"}]}]} 16:10:28 INFO - Return code: 0 16:10:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:28 INFO - # TBPL SUCCESS # 16:10:28 INFO - Running post-action listener: _resource_record_post_action 16:10:28 INFO - [mozharness: 2017-05-23 23:10:28.191602Z] Finished run-tests step (success) 16:10:28 INFO - Running post-run listener: _resource_record_post_run 16:10:28 INFO - Total resource usage - Wall time: 1258s; CPU: 13.0%; Read bytes: 8716288; Write bytes: 402030592; Read time: 2108; Write time: 419248 16:10:28 INFO - TinderboxPrint: CPU usage
12.8% 16:10:28 INFO - TinderboxPrint: I/O read bytes / time
8,716,288 / 2,108 16:10:28 INFO - TinderboxPrint: I/O write bytes / time
402,030,592 / 419,248 16:10:28 INFO - TinderboxPrint: CPU idle
8,757.8 (87.2%) 16:10:28 INFO - TinderboxPrint: CPU user
1,221.8 (12.2%) 16:10:28 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 98410496; Read time: 0; Write time: 100444 16:10:28 INFO - run-tests - Wall time: 1243s; CPU: 13.0%; Read bytes: 8716288; Write bytes: 303620096; Read time: 2108; Write time: 318804 16:10:28 INFO - Running post-run listener: _upload_blobber_files 16:10:28 INFO - Blob upload gear active. 16:10:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:29 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:10:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:10:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:30 INFO - (blobuploader) - INFO - Done attempting. 16:10:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:37 INFO - (blobuploader) - INFO - Done attempting. 16:10:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:10:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:10:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:37 INFO - (blobuploader) - INFO - Done attempting. 16:10:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:38 INFO - (blobuploader) - INFO - Done attempting. 16:10:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:38 INFO - Return code: 0 16:10:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:38 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4363ae4be13c2c5fc42c228e420cbc8c8c755292214bde8454e487896dd0760b47a2a807a363aac8e2286e63805e445ca634eeb7d2e2d059194784b109578b0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe388aa89d5d2317ac77f53722f15df6fec9b339c770f5dd5baf72731ed1f1f8ee6663125841ad87bc8dc2dc5a0ee2c01344de938a9020ef72f8269b8e0f6340", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63340ddbc87369b3eaa899ad4fbaca06bde44cdca47cf2972ac26a0a581fb869c3bb251dbd472fe2d07ed00d90f9cd3ed35efd43586f4402cf474457131d33bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/543d33d9c0fbfbc9b325ea23669cad33ba24dd5db57b43b41114010d92fdd7163d14ab5f56f137adcc6912dc441778b7f92e337f70c5fe0c2746e7e19c2b52b2"} 16:10:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:38 INFO - Contents: 16:10:38 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4363ae4be13c2c5fc42c228e420cbc8c8c755292214bde8454e487896dd0760b47a2a807a363aac8e2286e63805e445ca634eeb7d2e2d059194784b109578b0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe388aa89d5d2317ac77f53722f15df6fec9b339c770f5dd5baf72731ed1f1f8ee6663125841ad87bc8dc2dc5a0ee2c01344de938a9020ef72f8269b8e0f6340", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63340ddbc87369b3eaa899ad4fbaca06bde44cdca47cf2972ac26a0a581fb869c3bb251dbd472fe2d07ed00d90f9cd3ed35efd43586f4402cf474457131d33bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/543d33d9c0fbfbc9b325ea23669cad33ba24dd5db57b43b41114010d92fdd7163d14ab5f56f137adcc6912dc441778b7f92e337f70c5fe0c2746e7e19c2b52b2"} 16:10:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:38 INFO - Copying logs to upload dir... 16:10:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1322.136769 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-05-23 16:10:38.782950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 16:10:38.787954) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4363ae4be13c2c5fc42c228e420cbc8c8c755292214bde8454e487896dd0760b47a2a807a363aac8e2286e63805e445ca634eeb7d2e2d059194784b109578b0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe388aa89d5d2317ac77f53722f15df6fec9b339c770f5dd5baf72731ed1f1f8ee6663125841ad87bc8dc2dc5a0ee2c01344de938a9020ef72f8269b8e0f6340", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63340ddbc87369b3eaa899ad4fbaca06bde44cdca47cf2972ac26a0a581fb869c3bb251dbd472fe2d07ed00d90f9cd3ed35efd43586f4402cf474457131d33bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/543d33d9c0fbfbc9b325ea23669cad33ba24dd5db57b43b41114010d92fdd7163d14ab5f56f137adcc6912dc441778b7f92e337f70c5fe0c2746e7e19c2b52b2"} build_url:https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/S8aEo-RdQuG3FXr-RuPqQg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4363ae4be13c2c5fc42c228e420cbc8c8c755292214bde8454e487896dd0760b47a2a807a363aac8e2286e63805e445ca634eeb7d2e2d059194784b109578b0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe388aa89d5d2317ac77f53722f15df6fec9b339c770f5dd5baf72731ed1f1f8ee6663125841ad87bc8dc2dc5a0ee2c01344de938a9020ef72f8269b8e0f6340", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63340ddbc87369b3eaa899ad4fbaca06bde44cdca47cf2972ac26a0a581fb869c3bb251dbd472fe2d07ed00d90f9cd3ed35efd43586f4402cf474457131d33bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/543d33d9c0fbfbc9b325ea23669cad33ba24dd5db57b43b41114010d92fdd7163d14ab5f56f137adcc6912dc441778b7f92e337f70c5fe0c2746e7e19c2b52b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 16:10:38.815049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:10:38.815432) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1495579714.581116-1083635548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004416 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:10:38.898349) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 16:10:38.901696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 16:10:38.902010) ========= ========= Total master_lag: 0.18 =========