builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1462954075.62 results: success (0) buildid: 20160510223109 builduid: 052ff848384e43aa9a45b3c1382e3552 revision: d6c7ebc1417b9d27f75b57834a672ac8b9008163 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.620486) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.621130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.621628) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.638884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.639185) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 01:07:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2016-05-11 01:07:56 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157571 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.811527) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.812054) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018439 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.865381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:55.866011) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d6c7ebc1417b9d27f75b57834a672ac8b9008163 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d6c7ebc1417b9d27f75b57834a672ac8b9008163 --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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 01:07:56,309 truncating revision to first 12 chars 2016-05-11 01:07:56,310 Setting DEBUG logging. 2016-05-11 01:07:56,310 attempt 1/10 2016-05-11 01:07:56,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d6c7ebc1417b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 01:07:56,563 unpacking tar archive at: mozilla-inbound-d6c7ebc1417b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.530641 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:56.409938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:56.410264) ========= script_repo_revision: d6c7ebc1417b9d27f75b57834a672ac8b9008163 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:56.410655) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:56.410922) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 01:07:56.420336) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-05-11 01:07:56.420664) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:07:56 INFO - MultiFileLogger online at 20160511 01:07:56 in /builds/slave/test 01:07:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 01:07:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:07:56 INFO - {'append_to_log': False, 01:07:56 INFO - 'base_work_dir': '/builds/slave/test', 01:07:56 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 01:07:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:07:56 INFO - 'branch': 'Mozilla-Inbound', 01:07:56 INFO - 'buildbot_json_path': 'buildprops.json', 01:07:56 INFO - 'config_files': ('talos/linux_config.py',), 01:07:56 INFO - 'default_actions': ('clobber', 01:07:56 INFO - 'read-buildbot-config', 01:07:56 INFO - 'download-and-extract', 01:07:56 INFO - 'populate-webroot', 01:07:56 INFO - 'create-virtualenv', 01:07:56 INFO - 'install', 01:07:56 INFO - 'run-tests'), 01:07:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:07:56 INFO - 'download_minidump_stackwalk': True, 01:07:56 INFO - 'download_symbols': 'ondemand', 01:07:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:07:56 INFO - 'tooltool.py': '/tools/tooltool.py', 01:07:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:07:56 INFO - '/tools/misc-python/virtualenv.py')}, 01:07:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:07:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:07:56 INFO - 'installer_path': 'installer.exe', 01:07:56 INFO - 'log_level': 'info', 01:07:56 INFO - 'log_name': 'talos', 01:07:56 INFO - 'log_to_console': True, 01:07:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:07:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:07:56 INFO - 'opt_config_files': (), 01:07:56 INFO - 'pip_index': False, 01:07:56 INFO - 'sps_profile': False, 01:07:56 INFO - 'sps_profile_interval': 0, 01:07:56 INFO - 'suite': 'dromaeojs-e10s', 01:07:56 INFO - 'system_bits': '32', 01:07:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:07:56 INFO - 'title': 'talos-linux64-ix-067', 01:07:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:07:56 INFO - 'use_talos_json': True, 01:07:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:07:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:07:56 INFO - 'work_dir': 'build'} 01:07:56 INFO - ##### 01:07:56 INFO - ##### Running clobber step. 01:07:56 INFO - ##### 01:07:56 INFO - Running pre-action listener: _resource_record_pre_action 01:07:56 INFO - Running main action method: clobber 01:07:56 INFO - rmtree: /builds/slave/test/build 01:07:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:07:57 INFO - Running post-action listener: _resource_record_post_action 01:07:57 INFO - ##### 01:07:57 INFO - ##### Finished clobber step (success) 01:07:57 INFO - ##### 01:07:57 INFO - ##### 01:07:57 INFO - ##### Running read-buildbot-config step. 01:07:57 INFO - ##### 01:07:57 INFO - Running pre-action listener: _resource_record_pre_action 01:07:57 INFO - Running main action method: read_buildbot_config 01:07:57 INFO - Using buildbot properties: 01:07:57 INFO - { 01:07:57 INFO - "project": "", 01:07:57 INFO - "product": "firefox", 01:07:57 INFO - "script_repo_revision": "production", 01:07:57 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 01:07:57 INFO - "repository": "", 01:07:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 01:07:57 INFO - "buildid": "20160510223109", 01:07:57 INFO - "pgo_build": "True", 01:07:57 INFO - "basedir": "/builds/slave/test", 01:07:57 INFO - "buildnumber": 643, 01:07:57 INFO - "slavename": "talos-linux64-ix-067", 01:07:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:07:57 INFO - "platform": "ubuntu64_hw", 01:07:57 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 01:07:57 INFO - "branch": "mozilla-inbound", 01:07:57 INFO - "repo_path": "integration/mozilla-inbound", 01:07:57 INFO - "revision": "d6c7ebc1417b9d27f75b57834a672ac8b9008163", 01:07:57 INFO - "stage_platform": "linux64-pgo", 01:07:57 INFO - "builduid": "052ff848384e43aa9a45b3c1382e3552", 01:07:57 INFO - "slavebuilddir": "test" 01:07:57 INFO - } 01:07:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 01:07:57 INFO - Running post-action listener: _resource_record_post_action 01:07:57 INFO - ##### 01:07:57 INFO - ##### Finished read-buildbot-config step (success) 01:07:57 INFO - ##### 01:07:57 INFO - ##### 01:07:57 INFO - ##### Running download-and-extract step. 01:07:57 INFO - ##### 01:07:57 INFO - Running pre-action listener: _resource_record_pre_action 01:07:57 INFO - Running main action method: download_and_extract 01:07:57 INFO - mkdir: /builds/slave/test/build/tests 01:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:07:57 INFO - https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:07:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:07:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:07:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:07:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:07:58 INFO - Downloaded 1361 bytes. 01:07:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:07:58 INFO - Using the following test package requirements: 01:07:58 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:07:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:07:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:07:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'jsshell-linux-x86_64.zip'], 01:07:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:07:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:07:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:07:58 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:07:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:07:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:07:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:07:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:07:58 INFO - https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:07:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:07:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:07:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:08:00 INFO - Downloaded 23903142 bytes. 01:08:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:08:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:08:01 INFO - Return code: 0 01:08:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:01 INFO - https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:08:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:08:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:08:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:08:02 INFO - Downloaded 23903142 bytes. 01:08:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:08:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:08:03 INFO - Return code: 0 01:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:03 INFO - https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:08:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:08:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:08:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:08:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:08:04 INFO - Downloaded 10840152 bytes. 01:08:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:08:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 01:08:05 INFO - Return code: 0 01:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:05 INFO - https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:08:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:08:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:08:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:08:06 INFO - Downloaded 58342803 bytes. 01:08:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:08:06 INFO - mkdir: /builds/slave/test/properties 01:08:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:08:06 INFO - Writing to file /builds/slave/test/properties/build_url 01:08:06 INFO - Contents: 01:08:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:08:07 INFO - Running post-action listener: _resource_record_post_action 01:08:07 INFO - Running post-action listener: set_extra_try_arguments 01:08:07 INFO - ##### 01:08:07 INFO - ##### Finished download-and-extract step (success) 01:08:07 INFO - ##### 01:08:07 INFO - ##### 01:08:07 INFO - ##### Running populate-webroot step. 01:08:07 INFO - ##### 01:08:07 INFO - Running pre-action listener: _resource_record_pre_action 01:08:07 INFO - Running main action method: populate_webroot 01:08:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:08:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:08:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:08:07 INFO - '--tppagecycles', 01:08:07 INFO - '7'], 01:08:07 INFO - 'tests': ['tsvgm']}}, 01:08:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:08:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:08:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:08:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:08:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:08:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:08:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['damp', 'tps']}, 01:08:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'tests': ['damp', 'tps']}, 01:08:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['dromaeo_dom']}, 01:08:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:08:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'other-e10s': {'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['a11yr', 01:08:07 INFO - 'ts_paint', 01:08:07 INFO - 'tpaint', 01:08:07 INFO - 'sessionrestore', 01:08:07 INFO - 'sessionrestore_no_auto_restore', 01:08:07 INFO - 'tabpaint']}, 01:08:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['tsvgx', 01:08:07 INFO - 'tsvgr_opacity', 01:08:07 INFO - 'tart', 01:08:07 INFO - 'tscrollx', 01:08:07 INFO - 'cart']}, 01:08:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:08:07 INFO - 'tsvgr_opacity', 01:08:07 INFO - 'tart', 01:08:07 INFO - 'tscrollx', 01:08:07 INFO - 'cart']}, 01:08:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'talos_options': ['--disable-e10s'], 01:08:07 INFO - 'tests': ['tp5o']}, 01:08:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'tests': ['tp5o']}, 01:08:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'talos_options': ['--disable-e10s', 01:08:07 INFO - '--xperf_path', 01:08:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:08:07 INFO - 'tests': ['tp5n']}, 01:08:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:08:07 INFO - 'talos_options': ['--xperf_path', 01:08:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:08:07 INFO - 'tests': ['tp5n']}}, 01:08:07 INFO - 'talos.zip': {'path': '', 01:08:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:08:07 INFO - Running post-action listener: _resource_record_post_action 01:08:07 INFO - ##### 01:08:07 INFO - ##### Finished populate-webroot step (success) 01:08:07 INFO - ##### 01:08:07 INFO - ##### 01:08:07 INFO - ##### Running create-virtualenv step. 01:08:07 INFO - ##### 01:08:07 INFO - Running pre-action listener: _resource_record_pre_action 01:08:07 INFO - Running main action method: create_virtualenv 01:08:07 INFO - Creating virtualenv /builds/slave/test/build/venv 01:08: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 01:08:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:08:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:08:07 INFO - Using real prefix '/usr' 01:08:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:08:07 INFO - Installing distribute.............................................................................................................................................................................................done. 01:08:09 INFO - Installing pip.................done. 01:08:09 INFO - Return code: 0 01:08:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:08:09 INFO - Using env: {'DISPLAY': ':0', 01:08:09 INFO - 'HOME': '/home/cltbld', 01:08:09 INFO - 'LANG': 'en_US.UTF-8', 01:08:09 INFO - 'LANGUAGE': 'en_US:en', 01:08:09 INFO - 'LOGNAME': 'cltbld', 01:08:09 INFO - 'MAIL': '/var/mail/cltbld', 01:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:09 INFO - 'MOZ_NO_REMOTE': '1', 01:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:09 INFO - 'NO_EM_RESTART': '1', 01:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:09 INFO - 'PWD': '/builds/slave/test', 01:08:09 INFO - 'SHELL': '/bin/bash', 01:08:09 INFO - 'SHLVL': '1', 01:08:09 INFO - 'TERM': 'linux', 01:08:09 INFO - 'TMOUT': '86400', 01:08:09 INFO - 'USER': 'cltbld', 01:08:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:09 INFO - '_': '/tools/buildbot/bin/python'} 01:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:10 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 01:08:10 INFO - Cleaning up... 01:08:10 INFO - Return code: 0 01:08:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:10 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 01:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:08:10 INFO - Using env: {'DISPLAY': ':0', 01:08:10 INFO - 'HOME': '/home/cltbld', 01:08:10 INFO - 'LANG': 'en_US.UTF-8', 01:08:10 INFO - 'LANGUAGE': 'en_US:en', 01:08:10 INFO - 'LOGNAME': 'cltbld', 01:08:10 INFO - 'MAIL': '/var/mail/cltbld', 01:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:10 INFO - 'MOZ_NO_REMOTE': '1', 01:08:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:10 INFO - 'NO_EM_RESTART': '1', 01:08:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:10 INFO - 'PWD': '/builds/slave/test', 01:08:10 INFO - 'SHELL': '/bin/bash', 01:08:10 INFO - 'SHLVL': '1', 01:08:10 INFO - 'TERM': 'linux', 01:08:10 INFO - 'TMOUT': '86400', 01:08:10 INFO - 'USER': 'cltbld', 01:08:10 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:10 INFO - '_': '/tools/buildbot/bin/python'} 01:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:10 INFO - Downloading/unpacking psutil>=0.7.1 01:08:10 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 01:08:10 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 01:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:10 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 01:08:10 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 01:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:08:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:08:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:08:12 INFO - Installing collected packages: psutil 01:08:12 INFO - Running setup.py install for psutil 01:08:12 INFO - building 'psutil._psutil_linux' extension 01:08: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 01:08:12 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 01:08:12 INFO - building 'psutil._psutil_posix' extension 01:08:12 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 01:08:12 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 01:08:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:08:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:08:12 INFO - Successfully installed psutil 01:08:12 INFO - Cleaning up... 01:08:12 INFO - Return code: 0 01:08:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:08:12 INFO - Using env: {'DISPLAY': ':0', 01:08:12 INFO - 'HOME': '/home/cltbld', 01:08:12 INFO - 'LANG': 'en_US.UTF-8', 01:08:12 INFO - 'LANGUAGE': 'en_US:en', 01:08:12 INFO - 'LOGNAME': 'cltbld', 01:08:12 INFO - 'MAIL': '/var/mail/cltbld', 01:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:12 INFO - 'MOZ_NO_REMOTE': '1', 01:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:12 INFO - 'NO_EM_RESTART': '1', 01:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:12 INFO - 'PWD': '/builds/slave/test', 01:08:12 INFO - 'SHELL': '/bin/bash', 01:08:12 INFO - 'SHLVL': '1', 01:08:12 INFO - 'TERM': 'linux', 01:08:12 INFO - 'TMOUT': '86400', 01:08:12 INFO - 'USER': 'cltbld', 01:08:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:12 INFO - '_': '/tools/buildbot/bin/python'} 01:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:08:12 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 01:08:12 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 01:08:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:12 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 01:08:12 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 01:08:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:08:14 INFO - Installing collected packages: mozsystemmonitor 01:08:14 INFO - Running setup.py install for mozsystemmonitor 01:08:14 INFO - Successfully installed mozsystemmonitor 01:08:14 INFO - Cleaning up... 01:08:15 INFO - Return code: 0 01:08:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:08:15 INFO - Using env: {'DISPLAY': ':0', 01:08:15 INFO - 'HOME': '/home/cltbld', 01:08:15 INFO - 'LANG': 'en_US.UTF-8', 01:08:15 INFO - 'LANGUAGE': 'en_US:en', 01:08:15 INFO - 'LOGNAME': 'cltbld', 01:08:15 INFO - 'MAIL': '/var/mail/cltbld', 01:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:15 INFO - 'MOZ_NO_REMOTE': '1', 01:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:15 INFO - 'NO_EM_RESTART': '1', 01:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:15 INFO - 'PWD': '/builds/slave/test', 01:08:15 INFO - 'SHELL': '/bin/bash', 01:08:15 INFO - 'SHLVL': '1', 01:08:15 INFO - 'TERM': 'linux', 01:08:15 INFO - 'TMOUT': '86400', 01:08:15 INFO - 'USER': 'cltbld', 01:08:15 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:15 INFO - '_': '/tools/buildbot/bin/python'} 01:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:15 INFO - Downloading/unpacking blobuploader==1.2.4 01:08: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 01:08: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 01:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08: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 01:08: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 01:08:17 INFO - Downloading blobuploader-1.2.4.tar.gz 01:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:08:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:08: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 01:08: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 01:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08: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 01:08: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 01:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:08:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:08: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 01:08: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 01:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08: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 01:08: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 01:08:17 INFO - Downloading docopt-0.6.1.tar.gz 01:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:08:18 INFO - Installing collected packages: blobuploader, requests, docopt 01:08:18 INFO - Running setup.py install for blobuploader 01:08:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:08:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:08:18 INFO - Running setup.py install for requests 01:08:18 INFO - Running setup.py install for docopt 01:08:18 INFO - Successfully installed blobuploader requests docopt 01:08:18 INFO - Cleaning up... 01:08:18 INFO - Return code: 0 01:08:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:08:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:08:18 INFO - Using env: {'DISPLAY': ':0', 01:08:18 INFO - 'HOME': '/home/cltbld', 01:08:18 INFO - 'LANG': 'en_US.UTF-8', 01:08:18 INFO - 'LANGUAGE': 'en_US:en', 01:08:18 INFO - 'LOGNAME': 'cltbld', 01:08:18 INFO - 'MAIL': '/var/mail/cltbld', 01:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:18 INFO - 'MOZ_NO_REMOTE': '1', 01:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:18 INFO - 'NO_EM_RESTART': '1', 01:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:18 INFO - 'PWD': '/builds/slave/test', 01:08:18 INFO - 'SHELL': '/bin/bash', 01:08:18 INFO - 'SHLVL': '1', 01:08:18 INFO - 'TERM': 'linux', 01:08:18 INFO - 'TMOUT': '86400', 01:08:18 INFO - 'USER': 'cltbld', 01:08:18 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:18 INFO - '_': '/tools/buildbot/bin/python'} 01:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:08:18 INFO - Running setup.py (path:/tmp/pip-dGxCr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:08:18 INFO - Running setup.py (path:/tmp/pip-iXoxdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:08:18 INFO - Running setup.py (path:/tmp/pip-iGAlbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:08:19 INFO - Running setup.py (path:/tmp/pip-AvRDqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:08:19 INFO - Running setup.py (path:/tmp/pip-e3p5Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:08:19 INFO - Running setup.py (path:/tmp/pip-SoT3rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:08:19 INFO - Running setup.py (path:/tmp/pip-FwxOPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:08:19 INFO - Running setup.py (path:/tmp/pip-UJrL13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:08:19 INFO - Running setup.py (path:/tmp/pip-i1vyVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:08:19 INFO - Running setup.py (path:/tmp/pip-45wt9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:08:19 INFO - Running setup.py (path:/tmp/pip-HGkQJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:08:19 INFO - Running setup.py (path:/tmp/pip-HlWzKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:08:19 INFO - Running setup.py (path:/tmp/pip-Ex5Fti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:08:19 INFO - Running setup.py (path:/tmp/pip-tA5bry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:08:19 INFO - Running setup.py (path:/tmp/pip-Ua2Zwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:08:19 INFO - Running setup.py (path:/tmp/pip-0zm32m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:08:20 INFO - Running setup.py (path:/tmp/pip-KG98Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:08:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:08:20 INFO - Running setup.py install for manifestparser 01:08:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Running setup.py install for mozcrash 01:08:20 INFO - Running setup.py install for mozdebug 01:08:20 INFO - Running setup.py install for mozdevice 01:08:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Running setup.py install for mozfile 01:08:20 INFO - Running setup.py install for mozhttpd 01:08:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Running setup.py install for mozinfo 01:08:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Running setup.py install for mozInstall 01:08:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:08:20 INFO - Running setup.py install for mozleak 01:08:20 INFO - Running setup.py install for mozlog 01:08:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Running setup.py install for moznetwork 01:08:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Running setup.py install for mozprocess 01:08:21 INFO - Running setup.py install for mozprofile 01:08:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Running setup.py install for mozrunner 01:08:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Running setup.py install for mozscreenshot 01:08:21 INFO - Running setup.py install for moztest 01:08:21 INFO - Running setup.py install for mozversion 01:08:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:08:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:08:21 INFO - Cleaning up... 01:08:21 INFO - Return code: 0 01:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:08:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:08:21 INFO - Using env: {'DISPLAY': ':0', 01:08:21 INFO - 'HOME': '/home/cltbld', 01:08:21 INFO - 'LANG': 'en_US.UTF-8', 01:08:21 INFO - 'LANGUAGE': 'en_US:en', 01:08:21 INFO - 'LOGNAME': 'cltbld', 01:08:21 INFO - 'MAIL': '/var/mail/cltbld', 01:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:21 INFO - 'MOZ_NO_REMOTE': '1', 01:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:21 INFO - 'NO_EM_RESTART': '1', 01:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:21 INFO - 'PWD': '/builds/slave/test', 01:08:21 INFO - 'SHELL': '/bin/bash', 01:08:21 INFO - 'SHLVL': '1', 01:08:21 INFO - 'TERM': 'linux', 01:08:21 INFO - 'TMOUT': '86400', 01:08:21 INFO - 'USER': 'cltbld', 01:08:21 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:21 INFO - '_': '/tools/buildbot/bin/python'} 01:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:08:22 INFO - Running setup.py (path:/tmp/pip-VM4Mn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:08:22 INFO - Running setup.py (path:/tmp/pip-91ZpnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:08:22 INFO - Running setup.py (path:/tmp/pip-Ls2wzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:08:22 INFO - Running setup.py (path:/tmp/pip-w_TcMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:08:22 INFO - Running setup.py (path:/tmp/pip-xo4oPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:08:22 INFO - Running setup.py (path:/tmp/pip-fzYb0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:08:22 INFO - Running setup.py (path:/tmp/pip-Zl1wKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:08:22 INFO - Running setup.py (path:/tmp/pip-zWi2pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:08:22 INFO - Running setup.py (path:/tmp/pip-VJSnsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:08:22 INFO - Running setup.py (path:/tmp/pip-dviOlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:08:22 INFO - Running setup.py (path:/tmp/pip-NEztUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:08:22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:08:22 INFO - Running setup.py (path:/tmp/pip-oE07A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 01:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:08:22 INFO - Running setup.py (path:/tmp/pip-M6c4oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:08:23 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)) 01:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:08:23 INFO - Running setup.py (path:/tmp/pip-yMQhZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 01:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:08:23 INFO - Running setup.py (path:/tmp/pip-YvpWuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:08:23 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)) 01:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:08:23 INFO - Running setup.py (path:/tmp/pip-pFpFYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 01:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:08:23 INFO - Running setup.py (path:/tmp/pip-tcAEi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:08:23 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)) 01:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:08:23 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)) 01:08:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:08:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:08:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:08:23 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 01:08:23 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 01:08:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:23 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 01:08:23 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 01:08:25 INFO - Downloading blessings-1.6.tar.gz 01:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:08:25 INFO - Installing collected packages: blessings 01:08:25 INFO - Running setup.py install for blessings 01:08:25 INFO - Successfully installed blessings 01:08:25 INFO - Cleaning up... 01:08:25 INFO - Return code: 0 01:08:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:08:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:08:25 INFO - Reading from file tmpfile_stdout 01:08:25 INFO - Current package versions: 01:08:25 INFO - argparse == 1.2.1 01:08:25 INFO - blessings == 1.6 01:08:25 INFO - blobuploader == 1.2.4 01:08:25 INFO - docopt == 0.6.1 01:08:25 INFO - manifestparser == 1.1 01:08:25 INFO - mozInstall == 1.12 01:08:25 INFO - mozcrash == 0.17 01:08:25 INFO - mozdebug == 0.1 01:08:25 INFO - mozdevice == 0.48 01:08:25 INFO - mozfile == 1.2 01:08:25 INFO - mozhttpd == 0.7 01:08:25 INFO - mozinfo == 0.9 01:08:25 INFO - mozleak == 0.1 01:08:25 INFO - mozlog == 3.1 01:08:25 INFO - moznetwork == 0.27 01:08:25 INFO - mozprocess == 0.22 01:08:25 INFO - mozprofile == 0.28 01:08:25 INFO - mozrunner == 6.11 01:08:25 INFO - mozscreenshot == 0.1 01:08:25 INFO - mozsystemmonitor == 0.0 01:08:25 INFO - moztest == 0.7 01:08:25 INFO - mozversion == 1.4 01:08:25 INFO - psutil == 3.1.1 01:08:25 INFO - requests == 1.2.3 01:08:25 INFO - wsgiref == 0.1.2 01:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:08:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:08:25 INFO - Using env: {'DISPLAY': ':0', 01:08:25 INFO - 'HOME': '/home/cltbld', 01:08:25 INFO - 'LANG': 'en_US.UTF-8', 01:08:25 INFO - 'LANGUAGE': 'en_US:en', 01:08:25 INFO - 'LOGNAME': 'cltbld', 01:08:25 INFO - 'MAIL': '/var/mail/cltbld', 01:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:25 INFO - 'MOZ_NO_REMOTE': '1', 01:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:25 INFO - 'NO_EM_RESTART': '1', 01:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:25 INFO - 'PWD': '/builds/slave/test', 01:08:25 INFO - 'SHELL': '/bin/bash', 01:08:25 INFO - 'SHLVL': '1', 01:08:25 INFO - 'TERM': 'linux', 01:08:25 INFO - 'TMOUT': '86400', 01:08:25 INFO - 'USER': 'cltbld', 01:08:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:25 INFO - '_': '/tools/buildbot/bin/python'} 01:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 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)) 01:08:25 INFO - Cleaning up... 01:08:25 INFO - Return code: 0 01:08:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:08:25 INFO - Using env: {'DISPLAY': ':0', 01:08:25 INFO - 'HOME': '/home/cltbld', 01:08:25 INFO - 'LANG': 'en_US.UTF-8', 01:08:25 INFO - 'LANGUAGE': 'en_US:en', 01:08:25 INFO - 'LOGNAME': 'cltbld', 01:08:25 INFO - 'MAIL': '/var/mail/cltbld', 01:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:25 INFO - 'MOZ_NO_REMOTE': '1', 01:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:25 INFO - 'NO_EM_RESTART': '1', 01:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:25 INFO - 'PWD': '/builds/slave/test', 01:08:25 INFO - 'SHELL': '/bin/bash', 01:08:25 INFO - 'SHLVL': '1', 01:08:25 INFO - 'TERM': 'linux', 01:08:25 INFO - 'TMOUT': '86400', 01:08:25 INFO - 'USER': 'cltbld', 01:08:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:25 INFO - '_': '/tools/buildbot/bin/python'} 01:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:26 INFO - Downloading/unpacking jsonschema 01:08:26 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 01:08:26 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 01:08:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:26 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 01:08:26 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 01:08:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:08:27 INFO - Downloading/unpacking functools32 (from jsonschema) 01:08: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 01:08: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 01:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08: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 01:08: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 01:08:28 INFO - Downloading functools32-3.2.3-2.tar.gz 01:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:08:28 INFO - warning: no files found matching '*.txt' 01:08:28 INFO - no previously-included directories found matching 'build' 01:08:28 INFO - no previously-included directories found matching 'dist' 01:08:28 INFO - no previously-included directories found matching '.git*' 01:08:28 INFO - Installing collected packages: jsonschema, functools32 01:08:28 INFO - Running setup.py install for functools32 01:08:28 INFO - warning: no files found matching '*.txt' 01:08:28 INFO - no previously-included directories found matching 'build' 01:08:28 INFO - no previously-included directories found matching 'dist' 01:08:28 INFO - no previously-included directories found matching '.git*' 01:08:28 INFO - Successfully installed jsonschema functools32 01:08:28 INFO - Cleaning up... 01:08:28 INFO - Return code: 0 01:08:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:08: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5cc24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', '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 01:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:08:28 INFO - Using env: {'DISPLAY': ':0', 01:08:28 INFO - 'HOME': '/home/cltbld', 01:08:28 INFO - 'LANG': 'en_US.UTF-8', 01:08:28 INFO - 'LANGUAGE': 'en_US:en', 01:08:28 INFO - 'LOGNAME': 'cltbld', 01:08:28 INFO - 'MAIL': '/var/mail/cltbld', 01:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:28 INFO - 'MOZ_NO_REMOTE': '1', 01:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:28 INFO - 'NO_EM_RESTART': '1', 01:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:28 INFO - 'PWD': '/builds/slave/test', 01:08:28 INFO - 'SHELL': '/bin/bash', 01:08:28 INFO - 'SHLVL': '1', 01:08:28 INFO - 'TERM': 'linux', 01:08:28 INFO - 'TMOUT': '86400', 01:08:28 INFO - 'USER': 'cltbld', 01:08:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:28 INFO - '_': '/tools/buildbot/bin/python'} 01:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:08:28 INFO - Downloading/unpacking flake8 01:08:28 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 01:08:28 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 01:08:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:28 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 01:08:28 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 01:08:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:08:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:08:30 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 01:08:30 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 01:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 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 01:08:30 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 01:08:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:08:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:08:30 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 01:08:30 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 01:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 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 01:08:30 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 01:08:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:08:30 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 01:08:30 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 01:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:08:30 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 01:08:30 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 01:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:08:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:08:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:08:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:08:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:08:31 INFO - no previously-included directories found matching 'docs/_build' 01:08:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:08:31 INFO - Running setup.py install for pep8 01:08:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:08:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:08:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:08:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:08:31 INFO - no previously-included directories found matching 'docs/_build' 01:08:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:08:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:08:31 INFO - Cleaning up... 01:08:31 INFO - Return code: 0 01:08:31 INFO - Running post-action listener: _resource_record_post_action 01:08:31 INFO - Running post-action listener: _start_resource_monitoring 01:08:31 INFO - Starting resource monitoring. 01:08:31 INFO - ##### 01:08:31 INFO - ##### Finished create-virtualenv step (success) 01:08:31 INFO - ##### 01:08:31 INFO - ##### 01:08:31 INFO - ##### Running install step. 01:08:31 INFO - ##### 01:08:31 INFO - Running pre-action listener: _resource_record_pre_action 01:08:31 INFO - Running main action method: install 01:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:08:31 INFO - Reading from file tmpfile_stdout 01:08:31 INFO - Detecting whether we're running mozinstall >=1.0... 01:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:08:31 INFO - Reading from file tmpfile_stdout 01:08:31 INFO - Output received: 01:08:31 INFO - Usage: mozinstall [options] installer 01:08:31 INFO - Options: 01:08:31 INFO - -h, --help show this help message and exit 01:08:31 INFO - -d DEST, --destination=DEST 01:08:31 INFO - Directory to install application into. [default: 01:08:31 INFO - "/builds/slave/test"] 01:08:31 INFO - --app=APP Application being installed. [default: firefox] 01:08:31 INFO - mkdir: /builds/slave/test/build/application 01:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:08:48 INFO - Reading from file tmpfile_stdout 01:08:48 INFO - Output received: 01:08:48 INFO - /builds/slave/test/build/application/firefox/firefox 01:08:48 INFO - Running post-action listener: _resource_record_post_action 01:08:48 INFO - ##### 01:08:48 INFO - ##### Finished install step (success) 01:08:48 INFO - ##### 01:08:48 INFO - ##### 01:08:48 INFO - ##### Running run-tests step. 01:08:48 INFO - ##### 01:08:48 INFO - Running pre-action listener: _resource_record_pre_action 01:08:48 INFO - Running main action method: run_tests 01:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:08:48 INFO - Python 2.7.3 01:08:48 INFO - Return code: 0 01:08:48 INFO - grabbing minidump binary from tooltool 01:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:08:48 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 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:08:48 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 01:08:48 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 01:08:48 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 01:08:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:08:48 INFO - Return code: 0 01:08:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:08:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:08:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:08:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:08:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:08:49 INFO - Return code: 0 01:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 01:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 01:08:49 INFO - Using env: {'DISPLAY': ':0', 01:08:49 INFO - 'HOME': '/home/cltbld', 01:08:49 INFO - 'LANG': 'en_US.UTF-8', 01:08:49 INFO - 'LANGUAGE': 'en_US:en', 01:08:49 INFO - 'LOGNAME': 'cltbld', 01:08:49 INFO - 'MAIL': '/var/mail/cltbld', 01:08:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:08:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:08:49 INFO - 'MOZ_NO_REMOTE': '1', 01:08:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:08:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:08:49 INFO - 'NO_EM_RESTART': '1', 01:08:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:08:49 INFO - 'PWD': '/builds/slave/test', 01:08:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:08:49 INFO - 'SHELL': '/bin/bash', 01:08:49 INFO - 'SHLVL': '1', 01:08:49 INFO - 'TERM': 'linux', 01:08:49 INFO - 'TMOUT': '86400', 01:08:49 INFO - 'USER': 'cltbld', 01:08:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171', 01:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:08:49 INFO - '_': '/tools/buildbot/bin/python'} 01:08:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 01:08:49 INFO - mozversion application_buildid: 20160510223109 01:08:49 INFO - mozversion application_changeset: d6c7ebc1417b9d27f75b57834a672ac8b9008163 01:08:49 INFO - mozversion application_display_name: Nightly 01:08:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:08:49 INFO - mozversion application_name: Firefox 01:08:49 INFO - mozversion application_remotingname: firefox 01:08:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:08:49 INFO - mozversion application_vendor: Mozilla 01:08:49 INFO - mozversion application_version: 49.0a1 01:08:49 INFO - mozversion platform_buildid: 20160510223109 01:08:49 INFO - mozversion platform_changeset: d6c7ebc1417b9d27f75b57834a672ac8b9008163 01:08:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:08:49 INFO - mozversion platform_version: 49.0a1 01:08:49 INFO - using testdate: 1462954129 01:08:49 INFO - actual date: 1462954129 01:08:49 INFO - starting webserver on 'localhost:46856' 01:08:49 INFO - SUITE-START | Running 2 tests 01:08:49 INFO - TEST-START | dromaeo_css 01:08:49 INFO - operating with platform_type : linux_ 01:08:49 INFO - Initialising browser for dromaeo_css test... 01:08:49 INFO - TEST-INFO | started process 21509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfdmoZf/profile http://localhost:46856/getInfo.html) 01:08:52 INFO - PROCESS | 21509 | 01:08:52 INFO - PROCESS | 21509 | (process:21575): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:08:53 INFO - PROCESS | 21509 | __metrics Screen width/height:1600/1200 01:08:53 INFO - PROCESS | 21509 | colorDepth:24 01:08:53 INFO - PROCESS | 21509 | Browser inner width/height: 1024/697 01:08:53 INFO - PROCESS | 21509 | __metrics 01:08:54 ERROR - PROCESS | 21509 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 01:08:54 INFO - TEST-INFO | 21509: exit 0 01:08:54 INFO - Browser initialized. 01:08:54 INFO - Running cycle 1/1 for dromaeo_css test... 01:08:54 INFO - TEST-INFO | started process 21626 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfdmoZf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:08:56 INFO - PROCESS | 21626 | 01:08:56 INFO - PROCESS | 21626 | (process:21689): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:09:06 INFO - PROCESS | 21626 | RSS: Main: 163311616 01:09:06 INFO - PROCESS | 21626 | 01:09:56 INFO - PROCESS | 21626 | 1462954196401 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:12:03 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46856/tests/dromaeo/cssquery-ext.html) 01:12:05 INFO - PROCESS | 21626 | RSS: Main: 166707200 01:12:05 INFO - PROCESS | 21626 | 01:15:07 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-ext.html (next: http://localhost:46856/tests/dromaeo/cssquery-jquery.html) 01:15:07 INFO - PROCESS | 21626 | RSS: Main: 166555648 01:15:07 INFO - PROCESS | 21626 | 01:18:09 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46856/tests/dromaeo/cssquery-mootools.html) 01:18:10 INFO - PROCESS | 21626 | RSS: Main: 167456768 01:18:10 INFO - PROCESS | 21626 | 01:21:11 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46856/tests/dromaeo/cssquery-prototype.html) 01:21:12 INFO - PROCESS | 21626 | RSS: Main: 165285888 01:21:12 INFO - PROCESS | 21626 | 01:24:14 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46856/tests/dromaeo/cssquery-yui.html) 01:24:15 INFO - PROCESS | 21626 | RSS: Main: 166760448 01:24:15 INFO - PROCESS | 21626 | 01:27:17 INFO - PROCESS | 21626 | Cycle 1(1): loaded http://localhost:46856/tests/dromaeo/cssquery-yui.html (next: http://localhost:46856/tests/dromaeo/cssquery-dojo.html) 01:27:18 INFO - PROCESS | 21626 | RSS: Main: 168366080 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | __start_tp_report 01:27:18 INFO - PROCESS | 21626 | _x_x_mozilla_page_load 01:27:18 INFO - PROCESS | 21626 | _x_x_mozilla_page_load_details 01:27:18 INFO - PROCESS | 21626 | |i|pagename|runs| 01:27:18 INFO - PROCESS | 21626 | |0;dojo.html;3199.168216263771;3199.488081906895;3229.4832826747725;3231.4668079766834;3252.8698852045904;5433.157860531619;5441.455854414557;5479.342478902532;5548.195511650808;5588.105903055512;5337.14605663093;5453.400125986148;5483.177523371496;5524.558035357162;5612.747426365813;4847.660663753539;4859.902801943959;5031;5046.394432668067;5112.105381558231;686.5795602953834;4849.587785038276;4956.430010548797;4997.250412438127;5063.6202284828605;4977.203647416414;4983.6013118950395;5014.8244811431605;5027.874303142433;5028.522290382426;5034.622403319729;5051.343325367716;5055.4186268579215;5061.6962982221185;5084.288199652052;4947.183714687072;4992.625553083497;5008.974955125237;5033.345665063555;5037.219231019198;5480;5502.394736578973;5508.421615730331;5557.027520183992;5596.552275817925;5751.626144300607;5817.767289308423;5935.584509084366;5945.554083443759;5947.49446297061;5913.615614985012;5939.287285525721;5982.162497250389;5993.580449368546;6010.96994515029;5529.502344788988;5561.332640083216;5572.0527510323345;5590.161475778666;5599.972000140014;6019.909701354483;6059.363766804422;6100.883963092903;6107.083937409336;6125.356837532084;5936.614120082181;5940.970295148583;5944.673042982591;5951.940480595225;5975.551833612496;6020.849478763066;6021.668808215591;6031.24609423822;6052.243469566304;6059.121427393004;5780.364159942403;5826.504747096545;5870.090136028923;5880.117982302689;6003.429674180946;5857.26784151981;5863.794767183128;5886.117082437583;5898.439648233411;5911.290645122613;4428.977855110704;4450.732956022649;4477.597016268486;4484.529124441887;4499.68502204849;3928.4303775952326;3932.6853851691794;3933.645971862546;3958.0896393829003;3962.782046987443;5592.3009623797025;5614.9719251403485;5616.157576363578;5644.6613203207935;5688.601797874109;6073.30157031947;6090.421409966046;6098.847528811726;6100.115483254903;6107.389261073857;5426.294581704353;5593.216949627055;5621.156826476062;5636.746346414421;5667.688277144796;5159.638825282269;5159.845204643867;5169.095408303532;5179;5180.352455943007;5959.582829201914;6254;6256.405641464054;6261.436470717657;6266.843328916813;6016.157737916783;6036.275646922221;6063.393660633901;6095.299040610386;6096.055111457554;6212.9068063978175;6251.124842522141;6251.937480625135;6292.779752708633;6293.339199383999;5663.575231857545;5682.403347648603;5927.14056461802;5985.850353741017;6022;5897.941020589911;5907.40925907423;5934.643921364732;5973.910391344047;6011.128386384037;5861.472467477947;5862.589618726648;5887.8233652990475;5888.263967004124;5894.38108998549;5751.827445176651;5806.593538452268;5862.560307977004;5882.352941176553;5887.735051922759;5528.75120619565;5670.489655930986;5697.401772813961;5725.570582206268;5739.253896993364;5839.0949402842625;5894.528437724886;5902.616329938712;5903.793367232126;5905.527557795452;5698.886022279614;5710.400407957105;5728.971355143198;5763.135529670583;5781.479666829838 01:27:18 INFO - PROCESS | 21626 | |1;ext.html;605.9151718759372;640.7661203660663;654.4666097130842;664.6643445060243;665.0755449924609;42557.57442425575;44349.77825110874;44601;44679.32981005288;48866;43620.345694814605;45365.319520207224;45658;45720.542794572044;45728.085438291215;8720.258778003861;8844.07137250589;8892.955535222314;8910.064443233465;8942;8755.693550725726;8851.247643950272;8885;8887.844580204564;8920.241779448723;8923.687670931518;8923.73228803137;8962.775930601754;8980.506072166028;8990.056044115372;8910.688125915594;8941.418807777474;8948.821023579525;8977.192052715254;8998.235150012257;8637.265832404253;8737.519436430994;8757.956210218972;8776.034636190014;8788.077251888524;41674.79162604198;42020;42167.15665686669;42206.15587688229;42247.310107595964;3263.7225835803984;3381.8163642725094;3425.400554902882;3458;3511.7717348372275;32361.676383236336;32491.51262731061;32840.50739238889;32990.83504582486;33873.830630846685;3886.0867696091395;3897.6881849451975;3937.6849852012056;4000.4999375078114;4068.328725760246;318957;320254;323547;324737.9999999976;324836;33125.503117453256;55373.169402458996;55711.44288557063;56009.43990560124;56027;35176.82411587977;36953.815230923676;36964.44553331702;37062.258754824754;37116;14170.645733856736;14178.645533861736;14253.144811311355;14272.286385680674;14288.642783930278;12724.745505090032;12825.551105711254;12836.294003829878;12848.614541563767;12891.35543222835;8506.957465212634;8549.059603443617;8632.093630168867;8664.09027052163;8696.695615653422;3328.7170590499595;3339.1151344893624;3375.1730825947798;3402.625711171769;3419.863205471803;984.9852252216223;995.721198064543;1081.6322450366913;1179.4633441783967;1204.5121725701106;31666.525002124985;31672.841635792134;32379.838100809346;32695.50956735651;32894.67105328964;1101.130107215307;1170.303669316755;1175.6473058082713;1181.161375423459;1186.9940650296867;16170;16554.6689066218;16602.58493537647;16913.577160571083;17219.22513486896;503891;506844;511472;511625;511836;59882.40117598768;60668.69665651643;61009.389906101256;61022.694886525285;61077.694611526655;1409.3587417724912;1421.047897908404;1445.9566213013627;1447.3920953199681;1460.6859525202344;29791;30417.631206596197;30840.228994275323;31024.22439439032;31751.682483175795;53338;53932.73033634964;53966.19050714321;54080.459195407544;54222.45777542174;426142;428882;429798;430264;430554;43818.56181438272;43892.7438540833;45314.773426132655;45334;45339.546604533534;49126;49322.50677493179;49354.50645493499;50384;50473.747631261605;41627.257308190434;42444.363334550624;43037;43102.78448607862;43140.568594313656;42611.7869410651;42630.5736942639;43010.35484467673;43414.782926085165;43479.34780978351;37392.62607373892;42001;42205;42299.57700422956;42680.14639707251;36688.81655591705;36759;37116.81441592775;37271.25457490889;37492;28119.43761124807;28739;29150.854245728635;29220.70779292265;29454.852725736233 01:27:18 INFO - PROCESS | 21626 | |2;jquery.html;405.61466606723616;407.4270978769073;409.3736583027966;410.06709735352086;428.7341848054205;15453.690926181469;17686.46940591781;18537;19444;19516.121774520147;18371.54071148222;18705.625887482278;18880.433586992378;18908.432747017578;18942.715859262124;5453.127499600065;5520.1167813150005;5637.379888212294;5640.520555752766;5642.210090587321;5566.805161819337;5576.888462230753;5598.1322894951345;5612.35457922338;5612.466815652507;5376.354837419495;5395.757190926398;5410.377806552257;5418.051840927842;5441.401445840955;5313.760880760375;5353.411124776291;5359.705216213107;5374.435684253156;5384.91922621161;4915.361003069614;4998.450170481244;5009.44896061433;5010.298558201855;5015.32293140425;22045.33863984067;22072.33782986513;22729.77270227293;23085.76914230853;23141.652875206884;2272.6363781795;2309.84985975911;2549.910753123632;2553.157458038843;2646.272275124337;14370.353334099991;14399.928000360036;14488.420463181461;14623.488177913721;14827;1941.145895805855;1999.200319872063;2012.4767560434245;2013.0438041929965;2023.1603884387962;80784.59607701923;84734.57632711797;84901.57549212276;85268;85288.57355713243;10472;10544.52549635253;10626.628068017582;10729.624463143753;10742.3017503862;15218.467353642569;15233.3906643733;29405.558916616268;30842.228944276572;31040;9742.318037737432;10196.38821670702;10204.795904081877;10236.436995965145;10465.895341046644;8120.147384524658;8270.37972152091;8280.751577452686;8722.084181161013;8767.736967890973;8124.634391452399;8519.872201916976;8614.18165274298;8686.695965641173;8805.207531322212;3409.4203985322556;3517.4372100464316;3674.3937250353392;3694.445833124999;3724.3110024645525;990.0742805476903;991.7239934350539;1040.438163391775;1043.984340234897;1071.3357718214756;18239.452816415527;18774.812251877578;18823.81176188248;18823.90588047051;19239.13423895928;927.939683920543;986.9605215791431;1019.801138777946;1052.037385792007;1053.7049626104704;10669.519871605797;10832.67501974942;10834.133269338592;10973.780524389467;10977.341359518454;464174;481050;484849;487388;496810;94647;95821.04178958261;96257;96317.51841240749;96932.51533742287;1008.178334657252;1018.0430395428274;1027.209049032241;1035.994820025895;1037.4397825174321;16467.839745501635;16670.916645416695;16717.24772385221;16774.916125419295;16962;31508.527372088978;38483.230335393695;39280.41079383869;39452.40821387624;41656;55607.72196139155;55679.443205567426;56536.15195772149;56988.43011569831;57237.71381143234;7726.7681969541;8057.677692892219;8153.062397824327;8536.189062039097;8707.608157632794;9715.70852874415;13079.149855259378;13246.80129798073;13611.863881361456;13733.176009439465;11496.48265828023;11848.755880632754;12053.879461205275;12064.577739779066;12095.516179352728;11808.645740627795;11817.586384476503;12022.63932082039;12086.153969222421;12156.696082598006;11911.3448760319;11956.282623042645;12040.157188997036;12049.518019279481;12235.388230588613;7735.651895664596;8090.959545202236;8224.259816616533;8254.876176857231;8264.752057438287;8795.516246606243;12052.457639406073;12080.577179798665;12085.274883507018;12299.262044277371 01:27:18 INFO - PROCESS | 21626 | |3;mootools.html;1576.5428025872493;1593.0202925201002;1793.9820601793986;1868.1686649440987;1892.1390767200926;8020.11778704342;8601.612927418266;8690.17443342883;9271.76820579485;9308.255339572826;8665.133486651348;9023.954880225607;9037.18665320121;9068.546572671374;9134.086591340863;2391.5456063347997;2409.92758222591;2427.9393015174674;2432.927012189637;2440.1093600835743;2346.835721499496;2369.3839601703594;2378.5718570657273;2378.643203519477;2386.0813586769045;2381.1070848431837;2387.964180537285;2388.140269502986;2405.4707964247837;2406.783389494945;2374.7625237476286;2375.4655202579456;2410.770976757205;2416.4683769570665;2422.2733180046093;2335.9065637374656;2348.4481146930457;2355.199232261022;2377.702787151606;2378.3102900158933;8854.955725221333;8937.955310223408;8962.327825413055;8969.551522423853;8985.865212021825;5478.685115572234;5480.451954804487;5514.255575497296;5561.749721262553;5619.438056194389;3569.3218288525095;3600.567931848169;3677.1358730698266;3750.099976005739;3796.468494410785;1141.8344340070644;1147.5811328864904;1163.847790687222;1216.574199030341;2059.6086743518686;12074.577389791315;12204.633860984095;12343.876561234363;12837.807432888514;12933.159344642661;4824.758762061883;4930.161872481687;5007.4491805901325;5045.117104506697;5051.621128415383;10722.839157412645;10750.569977200981;10801.459927003618;10824.296420732628;10898.673039808818;5545.140503221926;5566.304211973504;5578.888422231533;5617.41017193197;5663;4598.057398233354;4772.49888761682;4875.7074575525585;4912.017596480719;5039.092963266574;4042.555318914917;4124.24877295485;4248.851290204828;4267.509236437787;4292.291771857608;1337.237774468563;1373.8763511283832;1374.9450021999207;1376.4700590272816;1379.9571943863757;965.4159233663673;980.4166520920041;997.9900200998043;1011.8077565262577;1014.4319181258371;5977.850553736192;6109.725062372204;6143.232095988002;6215.81352559424;6471.434566273001;918.5361392496746;935.742670765545;936.6721647423348;945.8959514453404;960.6349587156835;4198.412222288882;4310.719803212781;4313.633341165973;4344.630706389992;4355.1725172217175;10928.398938058483;11327.490262938187;11409.543618255342;11412.486438110305;11545.595904143483;10726.624568140078;11170.329780213213;11271.492782824793;11439.256448330998;11612.071034317234;8349.707760228363;8503.064662887202;8503.787405314917;8511.446755960966;8591.183837535424;5924.733386997509;6005.429484198994;6007.579469437097;6036.9094463582205;6094.177286066501;10367.688969330931;10580.15358771312;10653.201009924442;10700.143988480748;10793.406362649815;10629.468526573795;10742.89257107419;10772.299800512941;10958;10985;7266.055412796302;7895.763127106196;7965.800854978672;8084.636191371284;8093.312068474245;8890.155435233642;8960.820783584162;8976.730698079067;9022.684206052756;9070.047644997445;7349.853002940018;7447.441441891988;7587.658555364911;7673.271039251262;7946.364290856834;7413.962930185315;7505.924940750522;7518.360939320219;7534.886976695244;7632.80917977055;7117.67970441321;7171.067761191011;7205.207427183111;7207.171175314906;7303.707851685873;5094.719790411563;5160.664556803796;5279.419263881046;5291.708956007456;5345.38528069277;5249.081410753027;5257.89484210306;5269.1569348903995;5275.630705850707;5276.314079169683 01:27:18 INFO - PROCESS | 21626 | |4;prototype.html;331.3257520944876;334.2796274029467;338.2744014089777;339.9864005439783;344.98965031049045;5547.694876781776;5675.404082571333;5675.60270781045;5681.403452637475;5716.428357164282;5575.135853942636;5594.832155035345;5599.552035837133;5601.887962240753;5623.04789985258;4084.89787755307;4279.764612946286;4303.548127446627;4313.956860431387;4317.417148684935;4194.78351278129;4264.424302719124;4267.893302667427;4287.099709061101;4307.461567304087;4228.97885510572;4250.596193361641;4260.744355338674;4270.829166833323;4293.420388247593;4256.936145957813;4293.8711838644895;4295.140971805651;4304.890603604243;4339.696221264481;3574.070741607175;3749.493818334517;3751.9624803751885;3757.6805971492454;3764.1154328733;5392.973035134838;5467.890642187173;5477.329414528567;5478.096114141162;5487.149491828773;4925.088858561177;4946.392422462714;4967.528084831935;4991.575716064102;5000.799968001275;5594.412586678422;5768.74040668171;5807.622504537191;5814.244148260699;5827.271591051119;4150.64719498843;4161.542230354688;4165;4174.582541745832;4183.560726123744;5910.763569457259;6061.848453788646;6149;6155.0151975683675;6176.505879529627;4185.686073544496;4197.271773347348;4222.6276460150575;4241.384999175102;4269.743815371087;4274.10243848789;4274.957250427518;4287.97856010718;4290.935635965463;4291.7768435995595;4897.975510122426;4961.206207006861;4986;4998.725070121178;5002.349694539686;5718.942810571841;5787;5832.24180856486;5865.530757539386;5919.6152250103605;1459.6861674739982;1486.2791546100132;1494.036346556465;1503.59413947959;1508.3966413434493;917.7797328641211;943.6366998705548;944.9055094490496;948.7912659214961;966.6616684160488;5061.620378471629;5457.808976685797;5553.0004599171725;5564.527015203672;5604;6056.728087101758;6067.423594758491;6068.302145253264;6106.236720409949;6106.4809491192855;5416.377116631637;5539.972300138474;5568.610197286151;5606.85982850432;5608.523275521545;3199.6800319968283;3202.59967504062;3219.6136463624516;3222.5166225066428;3239.303549736818;6240.188775459162;6252.562320637512;6264.467520260829;6282.874342513215;6326.2602784234705;5882.55880808941;6097.451229389376;6118.785842495491;6167;6184.6598437085495;6299.590526615755;6301.6219026858535;6301.8109456716375;6316.526260530479;6322.968385158045;5788.263520943784;6029.87940241184;6100.938990610214;6127.877442451215;6152.354002829638;5955.464008239279;5988.6706231157705;6003.339632640494;6068.726907289094;6102.7253773579405;6129.693515324127;6290.370962903673;6296.685165741603;6335.271443784024;6367.42693157618;4193.874183774492;4230.407742915932;4230.7884605770205;4232.259354612992;4240.703150779415;6048.183495228088;6054.697265136638;6056.1824153740445;6098.603590766586;6141.324454309933;5661.915071273852;5836.883262334814;5860.736266868086;5884.411558844081;5990.730417131327;5848.4151584841175;5848.707564621837;5861.062230043172;5885.73514191871;5886.381245157445;5602.439756024365;5771.509421699203;5790.334111577223;5832.3292821326095;5840.8247752567495;5883.176355310346;5962;5965.34381218057;5975.4007006952115;5975.55183361241;5759.222504961944;5773.884522309614;5816.796412125555;5829.417058294137;5836.737346819318 01:27:18 INFO - PROCESS | 21626 | |5;yui.html;721;730.0910366593591;730.2369024369533;745.3813334932007;750.8685979953508;14040.929795351021;14348.78476822846;14355.64110897228;14705.852941470583;14798.186099764509;14215.644608884782;14333.498327558538;14343.78484322736;14345.497907573237;14394.568162955102;690.2752110284207;826.3348004856089;848.7962888906665;862.017300277684;862.0861886400404;836.4521238588734;838.9538575378351;843.026304618167;843.6709683223547;849.524266410812;885.4421714320001;886.4415418286503;890.8574628059512;891.6121487153076;893.0979710492385;867.9479231246146;870.2211520688948;878.4641368765048;878.97802554936;880.995595022024;641.1696852575925;644.0596728775993;645.3417514135602;646.6249575246346;648.2318452633644;14834.480793172188;14919.925400373037;14921.626959325995;14924.552263432006;14959.326830292663;14449.49426770058;14465.638359041002;14465.710685786331;14542.34559444817;14867.628309292246;10713.464326783629;10800.243982921198;10895.128389728803;10994.780104397947;11036.668899933014;3841.0973421245985;3875.5155605549307;3955.663768579674;3963.9405408918888;4002.0195052212393;57282;57504.712476437766;57519.42480575183;57613.71193144008;57694;10694.518746656264;11062.778744425066;11093.500792464358;11131.89249099469;11289.0968722502;20546.486337841674;20794.79205207959;20809.58380832375;20865.68701469479;20902.477438063866;8295.336373090258;8297.087320394752;8324.417290789708;8358.289545388589;8456.830863382698;4427.468703755506;4474.910501789946;4513.210188217049;4542.295944128666;4562.977185114053;3609.1338078860886;3701.833417496165;3708.870189543407;3711.981440092782;3713.405855063177;638.5083485716066;654.2149420695184;690.7651398524525;716.4805516000962;716.638097760638;728.1662496441651;773.7137259214127;784.948978316419;792.1484404265437;803.4335793265734;9141.222996045277;9215.63137474507;9282.738139578749;9564.234861211087;9778.951105244429;730.7515444748811;767.8809784483414;771.9228077192236;780.1340512031641;781.3944193250163;4361.345798130243;4494.258447356215;4612.907741845212;4613.469451013177;4615.492295847454;56144;63726;66438.33561664322;66948;67614.32385676178;54909;57324.140137897964;57361.71319143461;58298;58723.11915321188;52696.73651631794;53419.73290133524;53506.197407038926;53839.19241211385;53879.19181212285;9532.904670953201;9690.224782017563;9714.854277185988;9747.853782193415;9837.360571562825;34254.48618270678;34958.65041349656;35341.29317413689;35659.82170089133;35808.10479738027;43784.56215437805;44923;45795.20980226472;46182.538174617825;46922.53077469318;12573.748525029265;12611;12846.871531284567;12853;12900.354982251038;13247.735045299232;13485.528006519724;13975.720485590027;14033.29833508341;14312.06971546846;12326.506939722669;12529.122961392615;12835;13057.804132938203;13119.540816071392;11091.556337746722;11442.427878606202;11479.426028698364;13127.277999710108;13184.802227966396;10814.405207713651;11282.661520154408;11441.542338306708;11626.88373116258;11721.882781172078;9727.416355018722;9790.314677972474;9949.950250248703;10001.34991225568;10007.949960250153;9980.60077596888;10195.286329957129;10476.947615261875;10477.9476102619;10483.423411712429 01:27:18 INFO - PROCESS | 21626 | __end_tp_report 01:27:18 INFO - PROCESS | 21626 | __start_cc_report 01:27:18 INFO - PROCESS | 21626 | _x_x_mozilla_cycle_collect,341 01:27:18 INFO - PROCESS | 21626 | __end_cc_report 01:27:18 INFO - PROCESS | 21626 | __startTimestamp1462955238021__endTimestamp 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | ------- Summary: start ------- 01:27:18 INFO - PROCESS | 21626 | Number of tests: 6 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#0] dojo.html Cycles:165 Average:5495.93 Median:5728.97 stddev:756.61 (13.2%) stddev-sans-first:737.17 01:27:18 INFO - PROCESS | 21626 | Values: 3199.2 3199.5 3229.5 3231.5 3252.9 5433.2 5441.5 5479.3 5548.2 5588.1 5337.1 5453.4 5483.2 5524.6 5612.7 4847.7 4859.9 5031.0 5046.4 5112.1 686.6 4849.6 4956.4 4997.3 5063.6 4977.2 4983.6 5014.8 5027.9 5028.5 5034.6 5051.3 5055.4 5061.7 5084.3 4947.2 4992.6 5009.0 5033.3 5037.2 5480.0 5502.4 5508.4 5557.0 5596.6 5751.6 5817.8 5935.6 5945.6 5947.5 5913.6 5939.3 5982.2 5993.6 6011.0 5529.5 5561.3 5572.1 5590.2 5600.0 6019.9 6059.4 6100.9 6107.1 6125.4 5936.6 5941.0 5944.7 5951.9 5975.6 6020.8 6021.7 6031.2 6052.2 6059.1 5780.4 5826.5 5870.1 5880.1 6003.4 5857.3 5863.8 5886.1 5898.4 5911.3 4429.0 4450.7 4477.6 4484.5 4499.7 3928.4 3932.7 3933.6 3958.1 3962.8 5592.3 5615.0 5616.2 5644.7 5688.6 6073.3 6090.4 6098.8 6100.1 6107.4 5426.3 5593.2 5621.2 5636.7 5667.7 5159.6 5159.8 5169.1 5179.0 5180.4 5959.6 6254.0 6256.4 6261.4 6266.8 6016.2 6036.3 6063.4 6095.3 6096.1 6212.9 6251.1 6251.9 6292.8 6293.3 5663.6 5682.4 5927.1 5985.9 6022.0 5897.9 5907.4 5934.6 5973.9 6011.1 5861.5 5862.6 5887.8 5888.3 5894.4 5751.8 5806.6 5862.6 5882.4 5887.7 5528.8 5670.5 5697.4 5725.6 5739.3 5839.1 5894.5 5902.6 5903.8 5905.5 5698.9 5710.4 5729.0 5763.1 5781.5 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#1] ext.html Cycles:180 Average:58077.78 Median:42584.68 stddev:113198.12 (265.8%) stddev-sans-first:113433.43 01:27:18 INFO - PROCESS | 21626 | Values: 605.9 640.8 654.5 664.7 665.1 42557.6 44349.8 44601.0 44679.3 48866.0 43620.3 45365.3 45658.0 45720.5 45728.1 8720.3 8844.1 8893.0 8910.1 8942.0 8755.7 8851.2 8885.0 8887.8 8920.2 8923.7 8923.7 8962.8 8980.5 8990.1 8910.7 8941.4 8948.8 8977.2 8998.2 8637.3 8737.5 8758.0 8776.0 8788.1 41674.8 42020.0 42167.2 42206.2 42247.3 3263.7 3381.8 3425.4 3458.0 3511.8 32361.7 32491.5 32840.5 32990.8 33873.8 3886.1 3897.7 3937.7 4000.5 4068.3 318957.0 320254.0 323547.0 324738.0 324836.0 33125.5 55373.2 55711.4 56009.4 56027.0 35176.8 36953.8 36964.4 37062.3 37116.0 14170.6 14178.6 14253.1 14272.3 14288.6 12724.7 12825.6 12836.3 12848.6 12891.4 8507.0 8549.1 8632.1 8664.1 8696.7 3328.7 3339.1 3375.2 3402.6 3419.9 985.0 995.7 1081.6 1179.5 1204.5 31666.5 31672.8 32379.8 32695.5 32894.7 1101.1 1170.3 1175.6 1181.2 1187.0 16170.0 16554.7 16602.6 16913.6 17219.2 503891.0 506844.0 511472.0 511625.0 511836.0 59882.4 60668.7 61009.4 61022.7 61077.7 1409.4 1421.0 1446.0 1447.4 1460.7 29791.0 30417.6 30840.2 31024.2 31751.7 53338.0 53932.7 53966.2 54080.5 54222.5 426142.0 428882.0 429798.0 430264.0 430554.0 43818.6 43892.7 45314.8 45334.0 45339.5 49126.0 49322.5 49354.5 50384.0 50473.7 41627.3 42444.4 43037.0 43102.8 43140.6 42611.8 42630.6 43010.4 43414.8 43479.3 37392.6 42001.0 42205.0 42299.6 42680.1 36688.8 36759.0 37116.8 37271.3 37492.0 28119.4 28739.0 29150.9 29220.7 29454.9 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#2] jquery.html Cycles:180 Average:29303.98 Median:30123.89 stddev:79716.27 (264.6%) stddev-sans-first:79910.36 01:27:18 INFO - PROCESS | 21626 | Values: 405.6 407.4 409.4 410.1 428.7 15453.7 17686.5 18537.0 19444.0 19516.1 18371.5 18705.6 18880.4 18908.4 18942.7 5453.1 5520.1 5637.4 5640.5 5642.2 5566.8 5576.9 5598.1 5612.4 5612.5 5376.4 5395.8 5410.4 5418.1 5441.4 5313.8 5353.4 5359.7 5374.4 5384.9 4915.4 4998.5 5009.4 5010.3 5015.3 22045.3 22072.3 22729.8 23085.8 23141.7 2272.6 2309.8 2549.9 2553.2 2646.3 14370.4 14399.9 14488.4 14623.5 14827.0 1941.1 1999.2 2012.5 2013.0 2023.2 80784.6 84734.6 84901.6 85268.0 85288.6 10472.0 10544.5 10626.6 10729.6 10742.3 15218.5 15233.4 29405.6 30842.2 31040.0 9742.3 10196.4 10204.8 10236.4 10465.9 8120.1 8270.4 8280.8 8722.1 8767.7 8124.6 8519.9 8614.2 8686.7 8805.2 3409.4 3517.4 3674.4 3694.4 3724.3 990.1 991.7 1040.4 1044.0 1071.3 18239.5 18774.8 18823.8 18823.9 19239.1 927.9 987.0 1019.8 1052.0 1053.7 10669.5 10832.7 10834.1 10973.8 10977.3 464174.0 481050.0 484849.0 487388.0 496810.0 94647.0 95821.0 96257.0 96317.5 96932.5 1008.2 1018.0 1027.2 1036.0 1037.4 16467.8 16670.9 16717.2 16774.9 16962.0 31508.5 38483.2 39280.4 39452.4 41656.0 55607.7 55679.4 56536.2 56988.4 57237.7 7726.8 8057.7 8153.1 8536.2 8707.6 9715.7 13079.1 13246.8 13611.9 13733.2 11496.5 11848.8 12053.9 12064.6 12095.5 11808.6 11817.6 12022.6 12086.2 12156.7 11911.3 11956.3 12040.2 12049.5 12235.4 7735.7 8091.0 8224.3 8254.9 8264.8 8795.5 12052.5 12080.6 12085.3 12299.3 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#3] mootools.html Cycles:180 Average:5994.44 Median:4893.86 stddev:3380.40 (69.1%) stddev-sans-first:3373.58 01:27:18 INFO - PROCESS | 21626 | Values: 1576.5 1593.0 1794.0 1868.2 1892.1 8020.1 8601.6 8690.2 9271.8 9308.3 8665.1 9024.0 9037.2 9068.5 9134.1 2391.5 2409.9 2427.9 2432.9 2440.1 2346.8 2369.4 2378.6 2378.6 2386.1 2381.1 2388.0 2388.1 2405.5 2406.8 2374.8 2375.5 2410.8 2416.5 2422.3 2335.9 2348.4 2355.2 2377.7 2378.3 8855.0 8938.0 8962.3 8969.6 8985.9 5478.7 5480.5 5514.3 5561.7 5619.4 3569.3 3600.6 3677.1 3750.1 3796.5 1141.8 1147.6 1163.8 1216.6 2059.6 12074.6 12204.6 12343.9 12837.8 12933.2 4824.8 4930.2 5007.4 5045.1 5051.6 10722.8 10750.6 10801.5 10824.3 10898.7 5545.1 5566.3 5578.9 5617.4 5663.0 4598.1 4772.5 4875.7 4912.0 5039.1 4042.6 4124.2 4248.9 4267.5 4292.3 1337.2 1373.9 1374.9 1376.5 1380.0 965.4 980.4 998.0 1011.8 1014.4 5977.9 6109.7 6143.2 6215.8 6471.4 918.5 935.7 936.7 945.9 960.6 4198.4 4310.7 4313.6 4344.6 4355.2 10928.4 11327.5 11409.5 11412.5 11545.6 10726.6 11170.3 11271.5 11439.3 11612.1 8349.7 8503.1 8503.8 8511.4 8591.2 5924.7 6005.4 6007.6 6036.9 6094.2 10367.7 10580.2 10653.2 10700.1 10793.4 10629.5 10742.9 10772.3 10958.0 10985.0 7266.1 7895.8 7965.8 8084.6 8093.3 8890.2 8960.8 8976.7 9022.7 9070.0 7349.9 7447.4 7587.7 7673.3 7946.4 7414.0 7505.9 7518.4 7534.9 7632.8 7117.7 7171.1 7205.2 7207.2 7303.7 5094.7 5160.7 5279.4 5291.7 5345.4 5249.1 5257.9 5269.2 5275.6 5276.3 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#4] prototype.html Cycles:180 Average:4928.79 Median:5607.69 stddev:1476.38 (26.3%) stddev-sans-first:1439.63 01:27:18 INFO - PROCESS | 21626 | Values: 331.3 334.3 338.3 340.0 345.0 5547.7 5675.4 5675.6 5681.4 5716.4 5575.1 5594.8 5599.6 5601.9 5623.0 4084.9 4279.8 4303.5 4314.0 4317.4 4194.8 4264.4 4267.9 4287.1 4307.5 4229.0 4250.6 4260.7 4270.8 4293.4 4256.9 4293.9 4295.1 4304.9 4339.7 3574.1 3749.5 3752.0 3757.7 3764.1 5393.0 5467.9 5477.3 5478.1 5487.1 4925.1 4946.4 4967.5 4991.6 5000.8 5594.4 5768.7 5807.6 5814.2 5827.3 4150.6 4161.5 4165.0 4174.6 4183.6 5910.8 6061.8 6149.0 6155.0 6176.5 4185.7 4197.3 4222.6 4241.4 4269.7 4274.1 4275.0 4288.0 4290.9 4291.8 4898.0 4961.2 4986.0 4998.7 5002.3 5718.9 5787.0 5832.2 5865.5 5919.6 1459.7 1486.3 1494.0 1503.6 1508.4 917.8 943.6 944.9 948.8 966.7 5061.6 5457.8 5553.0 5564.5 5604.0 6056.7 6067.4 6068.3 6106.2 6106.5 5416.4 5540.0 5568.6 5606.9 5608.5 3199.7 3202.6 3219.6 3222.5 3239.3 6240.2 6252.6 6264.5 6282.9 6326.3 5882.6 6097.5 6118.8 6167.0 6184.7 6299.6 6301.6 6301.8 6316.5 6323.0 5788.3 6029.9 6100.9 6127.9 6152.4 5955.5 5988.7 6003.3 6068.7 6102.7 6129.7 6290.4 6296.7 6335.3 6367.4 4193.9 4230.4 4230.8 4232.3 4240.7 6048.2 6054.7 6056.2 6098.6 6141.3 5661.9 5836.9 5860.7 5884.4 5990.7 5848.4 5848.7 5861.1 5885.7 5886.4 5602.4 5771.5 5790.3 5832.3 5840.8 5883.2 5962.0 5965.3 5975.4 5975.6 5759.2 5773.9 5816.8 5829.4 5836.7 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - PROCESS | 21626 | [#5] yui.html Cycles:180 Average:15231.37 Median:25204.06 stddev:17844.28 (70.8%) stddev-sans-first:17861.07 01:27:18 INFO - PROCESS | 21626 | Values: 721.0 730.1 730.2 745.4 750.9 14040.9 14348.8 14355.6 14705.9 14798.2 14215.6 14333.5 14343.8 14345.5 14394.6 690.3 826.3 848.8 862.0 862.1 836.5 839.0 843.0 843.7 849.5 885.4 886.4 890.9 891.6 893.1 867.9 870.2 878.5 879.0 881.0 641.2 644.1 645.3 646.6 648.2 14834.5 14919.9 14921.6 14924.6 14959.3 14449.5 14465.6 14465.7 14542.3 14867.6 10713.5 10800.2 10895.1 10994.8 11036.7 3841.1 3875.5 3955.7 3963.9 4002.0 57282.0 57504.7 57519.4 57613.7 57694.0 10694.5 11062.8 11093.5 11131.9 11289.1 20546.5 20794.8 20809.6 20865.7 20902.5 8295.3 8297.1 8324.4 8358.3 8456.8 4427.5 4474.9 4513.2 4542.3 4563.0 3609.1 3701.8 3708.9 3712.0 3713.4 638.5 654.2 690.8 716.5 716.6 728.2 773.7 784.9 792.1 803.4 9141.2 9215.6 9282.7 9564.2 9779.0 730.8 767.9 771.9 780.1 781.4 4361.3 4494.3 4612.9 4613.5 4615.5 56144.0 63726.0 66438.3 66948.0 67614.3 54909.0 57324.1 57361.7 58298.0 58723.1 52696.7 53419.7 53506.2 53839.2 53879.2 9532.9 9690.2 9714.9 9747.9 9837.4 34254.5 34958.7 35341.3 35659.8 35808.1 43784.6 44923.0 45795.2 46182.5 46922.5 12573.7 12611.0 12846.9 12853.0 12900.4 13247.7 13485.5 13975.7 14033.3 14312.1 12326.5 12529.1 12835.0 13057.8 13119.5 11091.6 11442.4 11479.4 13127.3 13184.8 10814.4 11282.7 11441.5 11626.9 11721.9 9727.4 9790.3 9950.0 10001.3 10007.9 9980.6 10195.3 10476.9 10477.9 10483.4 01:27:18 INFO - PROCESS | 21626 | -------- Summary: end -------- 01:27:18 INFO - PROCESS | 21626 | 01:27:18 INFO - TEST-INFO | 21626: exit 0 01:27:18 INFO - TEST-OK | dromaeo_css | took 1109096ms 01:27:18 INFO - TEST-START | kraken 01:27:18 INFO - operating with platform_type : linux_ 01:27:18 INFO - Initialising browser for kraken test... 01:27:18 INFO - TEST-INFO | started process 21865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1qPvkJ/profile http://localhost:46856/getInfo.html) 01:27:21 INFO - PROCESS | 21865 | 01:27:21 INFO - PROCESS | 21865 | (process:21934): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:27:22 INFO - PROCESS | 21865 | __metrics Screen width/height:1600/1200 01:27:22 INFO - PROCESS | 21865 | colorDepth:24 01:27:22 INFO - PROCESS | 21865 | Browser inner width/height: 1024/697 01:27:22 INFO - PROCESS | 21865 | __metrics 01:27:23 ERROR - PROCESS | 21865 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 01:27:23 INFO - TEST-INFO | 21865: exit 0 01:27:23 INFO - Browser initialized. 01:27:23 INFO - Running cycle 1/1 for kraken test... 01:27:23 INFO - TEST-INFO | started process 21985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1qPvkJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:25 INFO - PROCESS | 21985 | 01:27:25 INFO - PROCESS | 21985 | (process:22047): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:27:35 INFO - PROCESS | 21985 | RSS: Main: 163262464 01:27:35 INFO - PROCESS | 21985 | 01:27:38 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46856/tests/kraken/driver.html?testName=audio-beat-detection) 01:27:39 INFO - PROCESS | 21985 | RSS: Main: 167391232 01:27:39 INFO - PROCESS | 21985 | 01:27:41 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46856/tests/kraken/driver.html?testName=audio-dft) 01:27:42 INFO - PROCESS | 21985 | RSS: Main: 167505920 01:27:42 INFO - PROCESS | 21985 | 01:27:45 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46856/tests/kraken/driver.html?testName=audio-fft) 01:27:46 INFO - PROCESS | 21985 | RSS: Main: 162455552 01:27:46 INFO - PROCESS | 21985 | 01:27:48 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46856/tests/kraken/driver.html?testName=audio-oscillator) 01:27:48 INFO - PROCESS | 21985 | RSS: Main: 162594816 01:27:48 INFO - PROCESS | 21985 | 01:27:51 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46856/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:27:51 INFO - PROCESS | 21985 | RSS: Main: 163074048 01:27:51 INFO - PROCESS | 21985 | 01:27:54 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46856/tests/kraken/driver.html?testName=imaging-darkroom) 01:27:55 INFO - PROCESS | 21985 | RSS: Main: 163176448 01:27:55 INFO - PROCESS | 21985 | 01:27:58 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46856/tests/kraken/driver.html?testName=imaging-desaturate) 01:27:59 INFO - PROCESS | 21985 | RSS: Main: 162045952 01:27:59 INFO - PROCESS | 21985 | 01:28:02 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46856/tests/kraken/driver.html?testName=json-parse-financial) 01:28:03 INFO - PROCESS | 21985 | RSS: Main: 162758656 01:28:03 INFO - PROCESS | 21985 | 01:28:04 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46856/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:28:05 INFO - PROCESS | 21985 | RSS: Main: 162742272 01:28:05 INFO - PROCESS | 21985 | 01:28:07 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:28:07 INFO - PROCESS | 21985 | RSS: Main: 162930688 01:28:07 INFO - PROCESS | 21985 | 01:28:09 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:28:10 INFO - PROCESS | 21985 | RSS: Main: 162942976 01:28:10 INFO - PROCESS | 21985 | 01:28:13 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:28:13 INFO - PROCESS | 21985 | RSS: Main: 163430400 01:28:13 INFO - PROCESS | 21985 | 01:28:16 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:28:17 INFO - PROCESS | 21985 | RSS: Main: 163139584 01:28:17 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | Cycle 1(1): loaded http://localhost:46856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46856/tests/kraken/driver.html?testName=ai-astar) 01:28:19 INFO - PROCESS | 21985 | RSS: Main: 164589568 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | __start_tp_report 01:28:19 INFO - PROCESS | 21985 | _x_x_mozilla_page_load 01:28:19 INFO - PROCESS | 21985 | _x_x_mozilla_page_load_details 01:28:19 INFO - PROCESS | 21985 | |i|pagename|runs| 01:28:19 INFO - PROCESS | 21985 | |0;ai-astar;109;112;105;112;113;111;105;111;111;111 01:28:19 INFO - PROCESS | 21985 | |1;audio-beat-detection;155;126;154;216;127;153;166;127;191;127 01:28:19 INFO - PROCESS | 21985 | |2;audio-dft;163;163;161;162;164;172;163;165;162;163 01:28:19 INFO - PROCESS | 21985 | |3;audio-fft;74;77;75;159;76;75;76;77;154;77 01:28:19 INFO - PROCESS | 21985 | |4;audio-oscillator;92;91;177;91;91;91;143;93;93;151 01:28:19 INFO - PROCESS | 21985 | |5;imaging-gaussian-blur;118;118;117;117;118;118;118;117;118;117 01:28:19 INFO - PROCESS | 21985 | |6;imaging-darkroom;148;147;147;147;148;148;146;146;147;147 01:28:19 INFO - PROCESS | 21985 | |7;imaging-desaturate;96;96;95;96;95;96;95;96;95;97 01:28:19 INFO - PROCESS | 21985 | |8;json-parse-financial;59;61;58;59;61;59;59;108;56;57 01:28:19 INFO - PROCESS | 21985 | |9;json-stringify-tinderbox;60;60;60;61;61;60;60;61;61;60 01:28:19 INFO - PROCESS | 21985 | |10;stanford-crypto-aes;79;79;79;79;86;79;80;84;86;81 01:28:19 INFO - PROCESS | 21985 | |11;stanford-crypto-ccm;136;126;127;132;137;123;125;128;191;125 01:28:19 INFO - PROCESS | 21985 | |12;stanford-crypto-pbkdf2;171;169;175;169;169;170;174;169;166;169 01:28:19 INFO - PROCESS | 21985 | |13;stanford-crypto-sha256-iterative;65;68;67;65;69;65;64;65;64;65 01:28:19 INFO - PROCESS | 21985 | __end_tp_report 01:28:19 INFO - PROCESS | 21985 | __start_cc_report 01:28:19 INFO - PROCESS | 21985 | _x_x_mozilla_cycle_collect,874 01:28:19 INFO - PROCESS | 21985 | __end_cc_report 01:28:19 INFO - PROCESS | 21985 | __startTimestamp1462955299360__endTimestamp 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | ------- Summary: start ------- 01:28:19 INFO - PROCESS | 21985 | Number of tests: 14 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.83 (2.5%) stddev-sans-first:2.98 01:28:19 INFO - PROCESS | 21985 | Values: 109.0 112.0 105.0 112.0 113.0 111.0 105.0 111.0 111.0 111.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#1] audio-beat-detection Cycles:10 Average:154.20 Median:154.50 stddev:30.42 (19.7%) stddev-sans-first:32.27 01:28:19 INFO - PROCESS | 21985 | Values: 155.0 126.0 154.0 216.0 127.0 153.0 166.0 127.0 191.0 127.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.08 (1.9%) stddev-sans-first:3.26 01:28:19 INFO - PROCESS | 21985 | Values: 163.0 163.0 161.0 162.0 164.0 172.0 163.0 165.0 162.0 163.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#3] audio-fft Cycles:10 Average:92.00 Median:76.00 stddev:34.03 (44.8%) stddev-sans-first:35.46 01:28:19 INFO - PROCESS | 21985 | Values: 74.0 77.0 75.0 159.0 76.0 75.0 76.0 77.0 154.0 77.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#4] audio-oscillator Cycles:10 Average:111.30 Median:91.00 stddev:32.64 (35.9%) stddev-sans-first:33.86 01:28:19 INFO - PROCESS | 21985 | Values: 92.0 91.0 177.0 91.0 91.0 91.0 143.0 93.0 93.0 151.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 01:28:19 INFO - PROCESS | 21985 | Values: 118.0 118.0 117.0 117.0 118.0 118.0 118.0 117.0 118.0 117.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 01:28:19 INFO - PROCESS | 21985 | Values: 148.0 147.0 147.0 147.0 148.0 148.0 146.0 146.0 147.0 147.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 01:28:19 INFO - PROCESS | 21985 | Values: 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 97.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:59.00 stddev:15.64 (26.5%) stddev-sans-first:16.50 01:28:19 INFO - PROCESS | 21985 | Values: 59.0 61.0 58.0 59.0 61.0 59.0 59.0 108.0 56.0 57.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 01:28:19 INFO - PROCESS | 21985 | Values: 60.0 60.0 60.0 61.0 61.0 60.0 60.0 61.0 61.0 60.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:80.50 stddev:2.97 (3.7%) stddev-sans-first:3.05 01:28:19 INFO - PROCESS | 21985 | Values: 79.0 79.0 79.0 79.0 86.0 79.0 80.0 84.0 86.0 81.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:130.00 stddev:20.24 (15.6%) stddev-sans-first:21.47 01:28:19 INFO - PROCESS | 21985 | Values: 136.0 126.0 127.0 132.0 137.0 123.0 125.0 128.0 191.0 125.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:169.50 stddev:2.64 (1.6%) stddev-sans-first:2.78 01:28:19 INFO - PROCESS | 21985 | Values: 171.0 169.0 175.0 169.0 169.0 170.0 174.0 169.0 166.0 169.0 01:28:19 INFO - PROCESS | 21985 | 01:28:19 INFO - PROCESS | 21985 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.00 stddev:1.70 (2.6%) stddev-sans-first:1.79 01:28:19 INFO - PROCESS | 21985 | Values: 65.0 68.0 67.0 65.0 69.0 65.0 64.0 65.0 64.0 65.0 01:28:19 INFO - PROCESS | 21985 | -------- Summary: end -------- 01:28:19 INFO - PROCESS | 21985 | 01:28:20 INFO - TEST-INFO | 21985: exit 0 01:28:20 INFO - TEST-OK | kraken | took 61655ms 01:28:20 INFO - SUITE-END | took 1170s 01:28:20 INFO - Completed test suite (00:19:30) 01:28:20 INFO - javascript benchmark 01:28:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3199.168216263771, 3199.488081906895, 3229.4832826747725, 3231.4668079766834, 3252.8698852045904, 5433.157860531619, 5441.455854414557, 5479.342478902532, 5548.195511650808, 5588.105903055512, 5337.14605663093, 5453.400125986148, 5483.177523371496, 5524.558035357162, 5612.747426365813, 4847.660663753539, 4859.902801943959, 5031.0, 5046.394432668067, 5112.105381558231, 686.5795602953834, 4849.587785038276, 4956.430010548797, 4997.250412438127, 5063.6202284828605, 4977.203647416414, 4983.6013118950395, 5014.8244811431605, 5027.874303142433, 5028.522290382426, 5034.622403319729, 5051.343325367716, 5055.4186268579215, 5061.6962982221185, 5084.288199652052, 4947.183714687072, 4992.625553083497, 5008.974955125237, 5033.345665063555, 5037.219231019198, 5480.0, 5502.394736578973, 5508.421615730331, 5557.027520183992, 5596.552275817925, 5751.626144300607, 5817.767289308423, 5935.584509084366, 5945.554083443759, 5947.49446297061, 5913.615614985012, 5939.287285525721, 5982.162497250389, 5993.580449368546, 6010.96994515029, 5529.502344788988, 5561.332640083216, 5572.0527510323345, 5590.161475778666, 5599.972000140014, 6019.909701354483, 6059.363766804422, 6100.883963092903, 6107.083937409336, 6125.356837532084, 5936.614120082181, 5940.970295148583, 5944.673042982591, 5951.940480595225, 5975.551833612496, 6020.849478763066, 6021.668808215591, 6031.24609423822, 6052.243469566304, 6059.121427393004, 5780.364159942403, 5826.504747096545, 5870.090136028923, 5880.117982302689, 6003.429674180946, 5857.26784151981, 5863.794767183128, 5886.117082437583, 5898.439648233411, 5911.290645122613, 4428.977855110704, 4450.732956022649, 4477.597016268486, 4484.529124441887, 4499.68502204849, 3928.4303775952326, 3932.6853851691794, 3933.645971862546, 3958.0896393829003, 3962.782046987443, 5592.3009623797025, 5614.9719251403485, 5616.157576363578, 5644.6613203207935, 5688.601797874109, 6073.30157031947, 6090.421409966046, 6098.847528811726, 6100.115483254903, 6107.389261073857, 5426.294581704353, 5593.216949627055, 5621.156826476062, 5636.746346414421, 5667.688277144796, 5159.638825282269, 5159.845204643867, 5169.095408303532, 5179.0, 5180.352455943007, 5959.582829201914, 6254.0, 6256.405641464054, 6261.436470717657, 6266.843328916813, 6016.157737916783, 6036.275646922221, 6063.393660633901, 6095.299040610386, 6096.055111457554, 6212.9068063978175, 6251.124842522141, 6251.937480625135, 6292.779752708633, 6293.339199383999, 5663.575231857545, 5682.403347648603, 5927.14056461802, 5985.850353741017, 6022.0, 5897.941020589911, 5907.40925907423, 5934.643921364732, 5973.910391344047, 6011.128386384037, 5861.472467477947, 5862.589618726648, 5887.8233652990475, 5888.263967004124, 5894.38108998549, 5751.827445176651, 5806.593538452268, 5862.560307977004, 5882.352941176553, 5887.735051922759, 5528.75120619565, 5670.489655930986, 5697.401772813961, 5725.570582206268, 5739.253896993364, 5839.0949402842625, 5894.528437724886, 5902.616329938712, 5903.793367232126, 5905.527557795452, 5698.886022279614, 5710.400407957105, 5728.971355143198, 5763.135529670583, 5781.479666829838], "value": 5444.433338422526, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.9151718759372, 640.7661203660663, 654.4666097130842, 664.6643445060243, 665.0755449924609, 42557.57442425575, 44349.77825110874, 44601.0, 44679.32981005288, 48866.0, 43620.345694814605, 45365.319520207224, 45658.0, 45720.542794572044, 45728.085438291215, 8720.258778003861, 8844.07137250589, 8892.955535222314, 8910.064443233465, 8942.0, 8755.693550725726, 8851.247643950272, 8885.0, 8887.844580204564, 8920.241779448723, 8923.687670931518, 8923.73228803137, 8962.775930601754, 8980.506072166028, 8990.056044115372, 8910.688125915594, 8941.418807777474, 8948.821023579525, 8977.192052715254, 8998.235150012257, 8637.265832404253, 8737.519436430994, 8757.956210218972, 8776.034636190014, 8788.077251888524, 41674.79162604198, 42020.0, 42167.15665686669, 42206.15587688229, 42247.310107595964, 3263.7225835803984, 3381.8163642725094, 3425.400554902882, 3458.0, 3511.7717348372275, 32361.676383236336, 32491.51262731061, 32840.50739238889, 32990.83504582486, 33873.830630846685, 3886.0867696091395, 3897.6881849451975, 3937.6849852012056, 4000.4999375078114, 4068.328725760246, 318957.0, 320254.0, 323547.0, 324737.9999999976, 324836.0, 33125.503117453256, 55373.169402458996, 55711.44288557063, 56009.43990560124, 56027.0, 35176.82411587977, 36953.815230923676, 36964.44553331702, 37062.258754824754, 37116.0, 14170.645733856736, 14178.645533861736, 14253.144811311355, 14272.286385680674, 14288.642783930278, 12724.745505090032, 12825.551105711254, 12836.294003829878, 12848.614541563767, 12891.35543222835, 8506.957465212634, 8549.059603443617, 8632.093630168867, 8664.09027052163, 8696.695615653422, 3328.7170590499595, 3339.1151344893624, 3375.1730825947798, 3402.625711171769, 3419.863205471803, 984.9852252216223, 995.721198064543, 1081.6322450366913, 1179.4633441783967, 1204.5121725701106, 31666.525002124985, 31672.841635792134, 32379.838100809346, 32695.50956735651, 32894.67105328964, 1101.130107215307, 1170.303669316755, 1175.6473058082713, 1181.161375423459, 1186.9940650296867, 16170.0, 16554.6689066218, 16602.58493537647, 16913.577160571083, 17219.22513486896, 503891.0, 506844.0, 511472.0, 511625.0, 511836.0, 59882.40117598768, 60668.69665651643, 61009.389906101256, 61022.694886525285, 61077.694611526655, 1409.3587417724912, 1421.047897908404, 1445.9566213013627, 1447.3920953199681, 1460.6859525202344, 29791.0, 30417.631206596197, 30840.228994275323, 31024.22439439032, 31751.682483175795, 53338.0, 53932.73033634964, 53966.19050714321, 54080.459195407544, 54222.45777542174, 426142.0, 428882.0, 429798.0, 430264.0, 430554.0, 43818.56181438272, 43892.7438540833, 45314.773426132655, 45334.0, 45339.546604533534, 49126.0, 49322.50677493179, 49354.50645493499, 50384.0, 50473.747631261605, 41627.257308190434, 42444.363334550624, 43037.0, 43102.78448607862, 43140.568594313656, 42611.7869410651, 42630.5736942639, 43010.35484467673, 43414.782926085165, 43479.34780978351, 37392.62607373892, 42001.0, 42205.0, 42299.57700422956, 42680.14639707251, 36688.81655591705, 36759.0, 37116.81441592775, 37271.25457490889, 37492.0, 28119.43761124807, 28739.0, 29150.854245728635, 29220.70779292265, 29454.852725736233], "value": 19239.62996733136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.61466606723616, 407.4270978769073, 409.3736583027966, 410.06709735352086, 428.7341848054205, 15453.690926181469, 17686.46940591781, 18537.0, 19444.0, 19516.121774520147, 18371.54071148222, 18705.625887482278, 18880.433586992378, 18908.432747017578, 18942.715859262124, 5453.127499600065, 5520.1167813150005, 5637.379888212294, 5640.520555752766, 5642.210090587321, 5566.805161819337, 5576.888462230753, 5598.1322894951345, 5612.35457922338, 5612.466815652507, 5376.354837419495, 5395.757190926398, 5410.377806552257, 5418.051840927842, 5441.401445840955, 5313.760880760375, 5353.411124776291, 5359.705216213107, 5374.435684253156, 5384.91922621161, 4915.361003069614, 4998.450170481244, 5009.44896061433, 5010.298558201855, 5015.32293140425, 22045.33863984067, 22072.33782986513, 22729.77270227293, 23085.76914230853, 23141.652875206884, 2272.6363781795, 2309.84985975911, 2549.910753123632, 2553.157458038843, 2646.272275124337, 14370.353334099991, 14399.928000360036, 14488.420463181461, 14623.488177913721, 14827.0, 1941.145895805855, 1999.200319872063, 2012.4767560434245, 2013.0438041929965, 2023.1603884387962, 80784.59607701923, 84734.57632711797, 84901.57549212276, 85268.0, 85288.57355713243, 10472.0, 10544.52549635253, 10626.628068017582, 10729.624463143753, 10742.3017503862, 15218.467353642569, 15233.3906643733, 29405.558916616268, 30842.228944276572, 31040.0, 9742.318037737432, 10196.38821670702, 10204.795904081877, 10236.436995965145, 10465.895341046644, 8120.147384524658, 8270.37972152091, 8280.751577452686, 8722.084181161013, 8767.736967890973, 8124.634391452399, 8519.872201916976, 8614.18165274298, 8686.695965641173, 8805.207531322212, 3409.4203985322556, 3517.4372100464316, 3674.3937250353392, 3694.445833124999, 3724.3110024645525, 990.0742805476903, 991.7239934350539, 1040.438163391775, 1043.984340234897, 1071.3357718214756, 18239.452816415527, 18774.812251877578, 18823.81176188248, 18823.90588047051, 19239.13423895928, 927.939683920543, 986.9605215791431, 1019.801138777946, 1052.037385792007, 1053.7049626104704, 10669.519871605797, 10832.67501974942, 10834.133269338592, 10973.780524389467, 10977.341359518454, 464174.0, 481050.0, 484849.0, 487388.0, 496810.0, 94647.0, 95821.04178958261, 96257.0, 96317.51841240749, 96932.51533742287, 1008.178334657252, 1018.0430395428274, 1027.209049032241, 1035.994820025895, 1037.4397825174321, 16467.839745501635, 16670.916645416695, 16717.24772385221, 16774.916125419295, 16962.0, 31508.527372088978, 38483.230335393695, 39280.41079383869, 39452.40821387624, 41656.0, 55607.72196139155, 55679.443205567426, 56536.15195772149, 56988.43011569831, 57237.71381143234, 7726.7681969541, 8057.677692892219, 8153.062397824327, 8536.189062039097, 8707.608157632794, 9715.70852874415, 13079.149855259378, 13246.80129798073, 13611.863881361456, 13733.176009439465, 11496.48265828023, 11848.755880632754, 12053.879461205275, 12064.577739779066, 12095.516179352728, 11808.645740627795, 11817.586384476503, 12022.63932082039, 12086.153969222421, 12156.696082598006, 11911.3448760319, 11956.282623042645, 12040.157188997036, 12049.518019279481, 12235.388230588613, 7735.651895664596, 8090.959545202236, 8224.259816616533, 8254.876176857231, 8264.752057438287, 8795.516246606243, 12052.457639406073, 12080.577179798665, 12085.274883507018, 12299.262044277371], "value": 9717.590485394747, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.5428025872493, 1593.0202925201002, 1793.9820601793986, 1868.1686649440987, 1892.1390767200926, 8020.11778704342, 8601.612927418266, 8690.17443342883, 9271.76820579485, 9308.255339572826, 8665.133486651348, 9023.954880225607, 9037.18665320121, 9068.546572671374, 9134.086591340863, 2391.5456063347997, 2409.92758222591, 2427.9393015174674, 2432.927012189637, 2440.1093600835743, 2346.835721499496, 2369.3839601703594, 2378.5718570657273, 2378.643203519477, 2386.0813586769045, 2381.1070848431837, 2387.964180537285, 2388.140269502986, 2405.4707964247837, 2406.783389494945, 2374.7625237476286, 2375.4655202579456, 2410.770976757205, 2416.4683769570665, 2422.2733180046093, 2335.9065637374656, 2348.4481146930457, 2355.199232261022, 2377.702787151606, 2378.3102900158933, 8854.955725221333, 8937.955310223408, 8962.327825413055, 8969.551522423853, 8985.865212021825, 5478.685115572234, 5480.451954804487, 5514.255575497296, 5561.749721262553, 5619.438056194389, 3569.3218288525095, 3600.567931848169, 3677.1358730698266, 3750.099976005739, 3796.468494410785, 1141.8344340070644, 1147.5811328864904, 1163.847790687222, 1216.574199030341, 2059.6086743518686, 12074.577389791315, 12204.633860984095, 12343.876561234363, 12837.807432888514, 12933.159344642661, 4824.758762061883, 4930.161872481687, 5007.4491805901325, 5045.117104506697, 5051.621128415383, 10722.839157412645, 10750.569977200981, 10801.459927003618, 10824.296420732628, 10898.673039808818, 5545.140503221926, 5566.304211973504, 5578.888422231533, 5617.41017193197, 5663.0, 4598.057398233354, 4772.49888761682, 4875.7074575525585, 4912.017596480719, 5039.092963266574, 4042.555318914917, 4124.24877295485, 4248.851290204828, 4267.509236437787, 4292.291771857608, 1337.237774468563, 1373.8763511283832, 1374.9450021999207, 1376.4700590272816, 1379.9571943863757, 965.4159233663673, 980.4166520920041, 997.9900200998043, 1011.8077565262577, 1014.4319181258371, 5977.850553736192, 6109.725062372204, 6143.232095988002, 6215.81352559424, 6471.434566273001, 918.5361392496746, 935.742670765545, 936.6721647423348, 945.8959514453404, 960.6349587156835, 4198.412222288882, 4310.719803212781, 4313.633341165973, 4344.630706389992, 4355.1725172217175, 10928.398938058483, 11327.490262938187, 11409.543618255342, 11412.486438110305, 11545.595904143483, 10726.624568140078, 11170.329780213213, 11271.492782824793, 11439.256448330998, 11612.071034317234, 8349.707760228363, 8503.064662887202, 8503.787405314917, 8511.446755960966, 8591.183837535424, 5924.733386997509, 6005.429484198994, 6007.579469437097, 6036.9094463582205, 6094.177286066501, 10367.688969330931, 10580.15358771312, 10653.201009924442, 10700.143988480748, 10793.406362649815, 10629.468526573795, 10742.89257107419, 10772.299800512941, 10958.0, 10985.0, 7266.055412796302, 7895.763127106196, 7965.800854978672, 8084.636191371284, 8093.312068474245, 8890.155435233642, 8960.820783584162, 8976.730698079067, 9022.684206052756, 9070.047644997445, 7349.853002940018, 7447.441441891988, 7587.658555364911, 7673.271039251262, 7946.364290856834, 7413.962930185315, 7505.924940750522, 7518.360939320219, 7534.886976695244, 7632.80917977055, 7117.67970441321, 7171.067761191011, 7205.207427183111, 7207.171175314906, 7303.707851685873, 5094.719790411563, 5160.664556803796, 5279.419263881046, 5291.708956007456, 5345.38528069277, 5249.081410753027, 5257.89484210306, 5269.1569348903995, 5275.630705850707, 5276.314079169683], "value": 4826.2713935336205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.3257520944876, 334.2796274029467, 338.2744014089777, 339.9864005439783, 344.98965031049045, 5547.694876781776, 5675.404082571333, 5675.60270781045, 5681.403452637475, 5716.428357164282, 5575.135853942636, 5594.832155035345, 5599.552035837133, 5601.887962240753, 5623.04789985258, 4084.89787755307, 4279.764612946286, 4303.548127446627, 4313.956860431387, 4317.417148684935, 4194.78351278129, 4264.424302719124, 4267.893302667427, 4287.099709061101, 4307.461567304087, 4228.97885510572, 4250.596193361641, 4260.744355338674, 4270.829166833323, 4293.420388247593, 4256.936145957813, 4293.8711838644895, 4295.140971805651, 4304.890603604243, 4339.696221264481, 3574.070741607175, 3749.493818334517, 3751.9624803751885, 3757.6805971492454, 3764.1154328733, 5392.973035134838, 5467.890642187173, 5477.329414528567, 5478.096114141162, 5487.149491828773, 4925.088858561177, 4946.392422462714, 4967.528084831935, 4991.575716064102, 5000.799968001275, 5594.412586678422, 5768.74040668171, 5807.622504537191, 5814.244148260699, 5827.271591051119, 4150.64719498843, 4161.542230354688, 4165.0, 4174.582541745832, 4183.560726123744, 5910.763569457259, 6061.848453788646, 6149.0, 6155.0151975683675, 6176.505879529627, 4185.686073544496, 4197.271773347348, 4222.6276460150575, 4241.384999175102, 4269.743815371087, 4274.10243848789, 4274.957250427518, 4287.97856010718, 4290.935635965463, 4291.7768435995595, 4897.975510122426, 4961.206207006861, 4986.0, 4998.725070121178, 5002.349694539686, 5718.942810571841, 5787.0, 5832.24180856486, 5865.530757539386, 5919.6152250103605, 1459.6861674739982, 1486.2791546100132, 1494.036346556465, 1503.59413947959, 1508.3966413434493, 917.7797328641211, 943.6366998705548, 944.9055094490496, 948.7912659214961, 966.6616684160488, 5061.620378471629, 5457.808976685797, 5553.0004599171725, 5564.527015203672, 5604.0, 6056.728087101758, 6067.423594758491, 6068.302145253264, 6106.236720409949, 6106.4809491192855, 5416.377116631637, 5539.972300138474, 5568.610197286151, 5606.85982850432, 5608.523275521545, 3199.6800319968283, 3202.59967504062, 3219.6136463624516, 3222.5166225066428, 3239.303549736818, 6240.188775459162, 6252.562320637512, 6264.467520260829, 6282.874342513215, 6326.2602784234705, 5882.55880808941, 6097.451229389376, 6118.785842495491, 6167.0, 6184.6598437085495, 6299.590526615755, 6301.6219026858535, 6301.8109456716375, 6316.526260530479, 6322.968385158045, 5788.263520943784, 6029.87940241184, 6100.938990610214, 6127.877442451215, 6152.354002829638, 5955.464008239279, 5988.6706231157705, 6003.339632640494, 6068.726907289094, 6102.7253773579405, 6129.693515324127, 6290.370962903673, 6296.685165741603, 6335.271443784024, 6367.42693157618, 4193.874183774492, 4230.407742915932, 4230.7884605770205, 4232.259354612992, 4240.703150779415, 6048.183495228088, 6054.697265136638, 6056.1824153740445, 6098.603590766586, 6141.324454309933, 5661.915071273852, 5836.883262334814, 5860.736266868086, 5884.411558844081, 5990.730417131327, 5848.4151584841175, 5848.707564621837, 5861.062230043172, 5885.73514191871, 5886.381245157445, 5602.439756024365, 5771.509421699203, 5790.334111577223, 5832.3292821326095, 5840.8247752567495, 5883.176355310346, 5962.0, 5965.34381218057, 5975.4007006952115, 5975.55183361241, 5759.222504961944, 5773.884522309614, 5816.796412125555, 5829.417058294137, 5836.737346819318], "value": 4451.590157652303, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.0, 730.0910366593591, 730.2369024369533, 745.3813334932007, 750.8685979953508, 14040.929795351021, 14348.78476822846, 14355.64110897228, 14705.852941470583, 14798.186099764509, 14215.644608884782, 14333.498327558538, 14343.78484322736, 14345.497907573237, 14394.568162955102, 690.2752110284207, 826.3348004856089, 848.7962888906665, 862.017300277684, 862.0861886400404, 836.4521238588734, 838.9538575378351, 843.026304618167, 843.6709683223547, 849.524266410812, 885.4421714320001, 886.4415418286503, 890.8574628059512, 891.6121487153076, 893.0979710492385, 867.9479231246146, 870.2211520688948, 878.4641368765048, 878.97802554936, 880.995595022024, 641.1696852575925, 644.0596728775993, 645.3417514135602, 646.6249575246346, 648.2318452633644, 14834.480793172188, 14919.925400373037, 14921.626959325995, 14924.552263432006, 14959.326830292663, 14449.49426770058, 14465.638359041002, 14465.710685786331, 14542.34559444817, 14867.628309292246, 10713.464326783629, 10800.243982921198, 10895.128389728803, 10994.780104397947, 11036.668899933014, 3841.0973421245985, 3875.5155605549307, 3955.663768579674, 3963.9405408918888, 4002.0195052212393, 57282.0, 57504.712476437766, 57519.42480575183, 57613.71193144008, 57694.0, 10694.518746656264, 11062.778744425066, 11093.500792464358, 11131.89249099469, 11289.0968722502, 20546.486337841674, 20794.79205207959, 20809.58380832375, 20865.68701469479, 20902.477438063866, 8295.336373090258, 8297.087320394752, 8324.417290789708, 8358.289545388589, 8456.830863382698, 4427.468703755506, 4474.910501789946, 4513.210188217049, 4542.295944128666, 4562.977185114053, 3609.1338078860886, 3701.833417496165, 3708.870189543407, 3711.981440092782, 3713.405855063177, 638.5083485716066, 654.2149420695184, 690.7651398524525, 716.4805516000962, 716.638097760638, 728.1662496441651, 773.7137259214127, 784.948978316419, 792.1484404265437, 803.4335793265734, 9141.222996045277, 9215.63137474507, 9282.738139578749, 9564.234861211087, 9778.951105244429, 730.7515444748811, 767.8809784483414, 771.9228077192236, 780.1340512031641, 781.3944193250163, 4361.345798130243, 4494.258447356215, 4612.907741845212, 4613.469451013177, 4615.492295847454, 56144.0, 63726.0, 66438.33561664322, 66948.0, 67614.32385676178, 54909.0, 57324.140137897964, 57361.71319143461, 58298.0, 58723.11915321188, 52696.73651631794, 53419.73290133524, 53506.197407038926, 53839.19241211385, 53879.19181212285, 9532.904670953201, 9690.224782017563, 9714.854277185988, 9747.853782193415, 9837.360571562825, 34254.48618270678, 34958.65041349656, 35341.29317413689, 35659.82170089133, 35808.10479738027, 43784.56215437805, 44923.0, 45795.20980226472, 46182.538174617825, 46922.53077469318, 12573.748525029265, 12611.0, 12846.871531284567, 12853.0, 12900.354982251038, 13247.735045299232, 13485.528006519724, 13975.720485590027, 14033.29833508341, 14312.06971546846, 12326.506939722669, 12529.122961392615, 12835.0, 13057.804132938203, 13119.540816071392, 11091.556337746722, 11442.427878606202, 11479.426028698364, 13127.277999710108, 13184.802227966396, 10814.405207713651, 11282.661520154408, 11441.542338306708, 11626.88373116258, 11721.882781172078, 9727.416355018722, 9790.314677972474, 9949.950250248703, 10001.34991225568, 10007.949960250153, 9980.60077596888, 10195.286329957129, 10476.947615261875, 10477.9476102619, 10483.423411712429], "value": 6891.945547118132, "unit": "score"}], "name": "dromaeo_css", "value": 7295.182977134168, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 105.0, 112.0, 113.0, 111.0, 105.0, 111.0, 111.0, 111.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 154.0, 216.0, 127.0, 153.0, 166.0, 127.0, 191.0, 127.0], "value": 154.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 164.0, 172.0, 163.0, 165.0, 162.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 159.0, 76.0, 75.0, 76.0, 77.0, 154.0, 77.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 177.0, 91.0, 91.0, 91.0, 143.0, 93.0, 93.0, 151.0], "value": 111.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 146.0, 146.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 97.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 58.0, 59.0, 61.0, 59.0, 59.0, 108.0, 56.0, 57.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 86.0, 79.0, 80.0, 84.0, 86.0, 81.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 126.0, 127.0, 132.0, 137.0, 123.0, 125.0, 128.0, 191.0, 125.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 175.0, 169.0, 169.0, 170.0, 174.0, 169.0, 166.0, 169.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 65.0, 69.0, 65.0, 64.0, 65.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1567.8000000000002, "alertThreshold": 2.0}]} 01:28:20 INFO - Return code: 0 01:28:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:28:20 ERROR - # TBPL SUCCESS # 01:28:20 INFO - Running post-action listener: _resource_record_post_action 01:28:20 INFO - ##### 01:28:20 INFO - ##### Finished run-tests step (success) 01:28:20 INFO - ##### 01:28:20 INFO - Running post-run listener: _resource_record_post_run 01:28:21 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546414592; Read time: 12; Write time: 446160 01:28:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 252325888; Read time: 0; Write time: 258320 01:28:21 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293978112; Read time: 12; Write time: 187772 01:28:21 INFO - Running post-run listener: _upload_blobber_files 01:28:21 INFO - Blob upload gear active. 01:28:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:28:21 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 01:28:21 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:28:21 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:28:22 INFO - (blobuploader) - INFO - Open directory for files ... 01:28:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:28:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:28:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:28:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:28:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:28:22 INFO - (blobuploader) - INFO - Done attempting. 01:28:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:28:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:28:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:28:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:28:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:28:23 INFO - (blobuploader) - INFO - Done attempting. 01:28:23 INFO - (blobuploader) - INFO - Iteration through files over. 01:28:23 INFO - Return code: 0 01:28:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:28:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:28:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/193110e6246fce34c212f367e3929d3a08de6ac05f2d8f1a5e518b96ede63431de5eb3e3ac150e1557a04c8c1f22d4da5fbc0e0c338e946f78744fd67f2424d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/72393a15bdcaa0a53ffc3b42bd0f57c01c68996e7c41d528f7708da341ad35d1850ef8c23686aa755bc809c73d81f2699ce203041855fd19548d5ce6edad6602"} 01:28:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:28:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:28:23 INFO - Contents: 01:28:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/193110e6246fce34c212f367e3929d3a08de6ac05f2d8f1a5e518b96ede63431de5eb3e3ac150e1557a04c8c1f22d4da5fbc0e0c338e946f78744fd67f2424d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/72393a15bdcaa0a53ffc3b42bd0f57c01c68996e7c41d528f7708da341ad35d1850ef8c23686aa755bc809c73d81f2699ce203041855fd19548d5ce6edad6602"} 01:28:23 INFO - Running post-run listener: copy_logs_to_upload_dir 01:28:23 INFO - Copying logs to upload dir... 01:28:23 INFO - mkdir: /builds/slave/test/build/upload/logs 01:28:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1226.828782 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-05-11 01:28:23.249214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 01:28:23.252865) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/193110e6246fce34c212f367e3929d3a08de6ac05f2d8f1a5e518b96ede63431de5eb3e3ac150e1557a04c8c1f22d4da5fbc0e0c338e946f78744fd67f2424d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/72393a15bdcaa0a53ffc3b42bd0f57c01c68996e7c41d528f7708da341ad35d1850ef8c23686aa755bc809c73d81f2699ce203041855fd19548d5ce6edad6602"} build_url:https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/XiPwv6RxTiauPG7Z03rIBQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/193110e6246fce34c212f367e3929d3a08de6ac05f2d8f1a5e518b96ede63431de5eb3e3ac150e1557a04c8c1f22d4da5fbc0e0c338e946f78744fd67f2424d3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/72393a15bdcaa0a53ffc3b42bd0f57c01c68996e7c41d528f7708da341ad35d1850ef8c23686aa755bc809c73d81f2699ce203041855fd19548d5ce6edad6602"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 01:28:23.278216) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:28:23.278926) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462953672.928080-816581171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 01:28:23.362640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 01:28:23.363360) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 01:28:23.363743) ========= ========= Total master_lag: 0.16 =========