builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-016 starttime: 1470258727.57 results: success (0) buildid: 20160803135021 builduid: 84881ea210d840bda6b39f12ba85ad71 revision: 265e7ad32cbe8749388575c61399ea34c413525b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.574726) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.575159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.575448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.592391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.592762) ========= 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=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-03 14:12:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2016-08-03 14:12:07 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125423 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.732211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.732540) ========= 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=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019348 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.782404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:07.782793) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 265e7ad32cbe8749388575c61399ea34c413525b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 265e7ad32cbe8749388575c61399ea34c413525b --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=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-03 14:12:07,913 truncating revision to first 12 chars 2016-08-03 14:12:07,913 Setting DEBUG logging. 2016-08-03 14:12:07,914 attempt 1/10 2016-08-03 14:12:07,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/265e7ad32cbe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 14:12:08,215 unpacking tar archive at: autoland-265e7ad32cbe/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623767 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:08.423595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:08.423930) ========= script_repo_revision: 265e7ad32cbe8749388575c61399ea34c413525b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:08.424322) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:08.424608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 14:12:08.435385) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-03 14:12:08.435718) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:12:08 INFO - MultiFileLogger online at 20160803 14:12:08 in /builds/slave/test 14:12:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 14:12:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:12:08 INFO - {'append_to_log': False, 14:12:08 INFO - 'base_work_dir': '/builds/slave/test', 14:12:08 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 14:12:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:12:08 INFO - 'branch': 'Autoland-Non-PGO', 14:12:08 INFO - 'buildbot_json_path': 'buildprops.json', 14:12:08 INFO - 'config_files': ('talos/linux_config.py',), 14:12:08 INFO - 'default_actions': ('clobber', 14:12:08 INFO - 'read-buildbot-config', 14:12:08 INFO - 'download-and-extract', 14:12:08 INFO - 'populate-webroot', 14:12:08 INFO - 'create-virtualenv', 14:12:08 INFO - 'install', 14:12:08 INFO - 'run-tests'), 14:12:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:12:08 INFO - 'download_minidump_stackwalk': True, 14:12:08 INFO - 'download_symbols': 'ondemand', 14:12:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:12:08 INFO - 'tooltool.py': '/tools/tooltool.py', 14:12:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:12:08 INFO - '/tools/misc-python/virtualenv.py')}, 14:12:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:12:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:12:08 INFO - 'installer_path': 'installer.exe', 14:12:08 INFO - 'log_level': 'info', 14:12:08 INFO - 'log_name': 'talos', 14:12:08 INFO - 'log_to_console': True, 14:12:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:12:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:12:08 INFO - 'opt_config_files': (), 14:12:08 INFO - 'pip_index': False, 14:12:08 INFO - 'sps_profile': False, 14:12:08 INFO - 'sps_profile_interval': 0, 14:12:08 INFO - 'suite': 'dromaeojs', 14:12:08 INFO - 'system_bits': '32', 14:12:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:12:08 INFO - 'title': 'talos-linux64-ix-016', 14:12:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:12:08 INFO - 'use_talos_json': True, 14:12:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:12:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:12:08 INFO - 'work_dir': 'build'} 14:12:08 INFO - [mozharness: 2016-08-03 21:12:08.653633Z] Running clobber step. 14:12:08 INFO - Running pre-action listener: _resource_record_pre_action 14:12:08 INFO - Running main action method: clobber 14:12:08 INFO - rmtree: /builds/slave/test/build 14:12:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:12:09 INFO - Running post-action listener: _resource_record_post_action 14:12:09 INFO - [mozharness: 2016-08-03 21:12:09.095178Z] Finished clobber step (success) 14:12:09 INFO - [mozharness: 2016-08-03 21:12:09.095279Z] Running read-buildbot-config step. 14:12:09 INFO - Running pre-action listener: _resource_record_pre_action 14:12:09 INFO - Running main action method: read_buildbot_config 14:12:09 INFO - Using buildbot properties: 14:12:09 INFO - { 14:12:09 INFO - "project": "", 14:12:09 INFO - "product": "firefox", 14:12:09 INFO - "script_repo_revision": "production", 14:12:09 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:12:09 INFO - "repository": "", 14:12:09 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 14:12:09 INFO - "buildid": "20160803135021", 14:12:09 INFO - "pgo_build": "False", 14:12:09 INFO - "basedir": "/builds/slave/test", 14:12:09 INFO - "buildnumber": 208, 14:12:09 INFO - "slavename": "talos-linux64-ix-016", 14:12:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:12:09 INFO - "platform": "ubuntu64_hw", 14:12:09 INFO - "scheduler": "tests-autoland-linux64-talos", 14:12:09 INFO - "branch": "autoland", 14:12:09 INFO - "repo_path": "integration/autoland", 14:12:09 INFO - "revision": "265e7ad32cbe8749388575c61399ea34c413525b", 14:12:09 INFO - "stage_platform": "linux64", 14:12:09 INFO - "builduid": "84881ea210d840bda6b39f12ba85ad71", 14:12:09 INFO - "slavebuilddir": "test" 14:12:09 INFO - } 14:12:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 14:12:09 INFO - Running post-action listener: _resource_record_post_action 14:12:09 INFO - [mozharness: 2016-08-03 21:12:09.098000Z] Finished read-buildbot-config step (success) 14:12:09 INFO - [mozharness: 2016-08-03 21:12:09.098087Z] Running download-and-extract step. 14:12:09 INFO - Running pre-action listener: _resource_record_pre_action 14:12:09 INFO - Running main action method: download_and_extract 14:12:09 INFO - mkdir: /builds/slave/test/build/tests 14:12: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')]} 14:12:09 INFO - https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:12:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:12:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:12:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:12:09 INFO - Downloaded 1361 bytes. 14:12:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:12:09 INFO - Using the following test package requirements: 14:12:09 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:12:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:12:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:12:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'jsshell-linux-x86_64.zip'], 14:12:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:12:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:12:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:12:09 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:12:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:12:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:12:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:12:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:12: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')]} 14:12:09 INFO - https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:12:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:12:10 INFO - Downloaded 20241568 bytes. 14:12:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:12:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:12:11 INFO - Return code: 0 14:12:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:12:11 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')]} 14:12:11 INFO - https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:12:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:12:12 INFO - Downloaded 20241568 bytes. 14:12:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:12:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:12:14 INFO - Return code: 0 14:12:14 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')]} 14:12:14 INFO - https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:12:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:12:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:12:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:12:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:12:14 INFO - Downloaded 14413723 bytes. 14:12:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:12:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:12:15 INFO - Return code: 0 14:12: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')]} 14:12:15 INFO - https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:12:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:12:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:12:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:12:16 INFO - Downloaded 55566509 bytes. 14:12:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:12:16 INFO - mkdir: /builds/slave/test/properties 14:12:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:12:16 INFO - Writing to file /builds/slave/test/properties/build_url 14:12:16 INFO - Contents: 14:12:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:12:17 INFO - Running post-action listener: _resource_record_post_action 14:12:17 INFO - Running post-action listener: set_extra_try_arguments 14:12:17 INFO - [mozharness: 2016-08-03 21:12:17.133895Z] Finished download-and-extract step (success) 14:12:17 INFO - [mozharness: 2016-08-03 21:12:17.134130Z] Running populate-webroot step. 14:12:17 INFO - Running pre-action listener: _resource_record_pre_action 14:12:17 INFO - Running main action method: populate_webroot 14:12:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:12:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:12:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:12:17 INFO - '--tppagecycles', 14:12:17 INFO - '7'], 14:12:17 INFO - 'tests': ['tsvgm']}}, 14:12:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:12:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:12:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:12:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:12:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['damp', 'tps']}, 14:12:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'tests': ['damp', 'tps']}, 14:12:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['dromaeo_dom']}, 14:12:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:12:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['basic_compositor_video']}, 14:12:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:12:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'other-e10s': {'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['a11yr', 14:12:17 INFO - 'ts_paint', 14:12:17 INFO - 'tpaint', 14:12:17 INFO - 'sessionrestore', 14:12:17 INFO - 'sessionrestore_no_auto_restore', 14:12:17 INFO - 'tabpaint']}, 14:12:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['tsvgx', 14:12:17 INFO - 'tsvgr_opacity', 14:12:17 INFO - 'tart', 14:12:17 INFO - 'tscrollx', 14:12:17 INFO - 'cart']}, 14:12:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:12:17 INFO - 'tsvgr_opacity', 14:12:17 INFO - 'tart', 14:12:17 INFO - 'tscrollx', 14:12:17 INFO - 'cart']}, 14:12:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'talos_options': ['--disable-e10s'], 14:12:17 INFO - 'tests': ['tp5o']}, 14:12:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'tests': ['tp5o']}, 14:12:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'talos_options': ['--disable-e10s', 14:12:17 INFO - '--xperf_path', 14:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:17 INFO - 'tests': ['tp5n']}, 14:12:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:17 INFO - 'talos_options': ['--xperf_path', 14:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:17 INFO - 'tests': ['tp5n']}}, 14:12:17 INFO - 'talos.zip': {'path': '', 14:12:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:12:17 INFO - Running post-action listener: _resource_record_post_action 14:12:17 INFO - [mozharness: 2016-08-03 21:12:17.149773Z] Finished populate-webroot step (success) 14:12:17 INFO - [mozharness: 2016-08-03 21:12:17.149862Z] Running create-virtualenv step. 14:12:17 INFO - Running pre-action listener: _resource_record_pre_action 14:12:17 INFO - Running main action method: create_virtualenv 14:12:17 INFO - Creating virtualenv /builds/slave/test/build/venv 14:12:17 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 14:12:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:12:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:12:17 INFO - Using real prefix '/usr' 14:12:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:12:17 INFO - Installing distribute.............................................................................................................................................................................................done. 14:12:19 INFO - Installing pip.................done. 14:12:19 INFO - Return code: 0 14:12:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:12:19 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')]} 14:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:19 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')]} 14:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:19 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:19 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 14:12:19 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 14:12:19 INFO - Using env: {'DISPLAY': ':0', 14:12:19 INFO - 'HOME': '/home/cltbld', 14:12:19 INFO - 'LANG': 'en_US.UTF-8', 14:12:19 INFO - 'LANGUAGE': 'en_US:en', 14:12:19 INFO - 'LOGNAME': 'cltbld', 14:12:19 INFO - 'MAIL': '/var/mail/cltbld', 14:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:19 INFO - 'MOZ_NO_REMOTE': '1', 14:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:19 INFO - 'NO_EM_RESTART': '1', 14:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:19 INFO - 'PWD': '/builds/slave/test', 14:12:19 INFO - 'SHELL': '/bin/bash', 14:12:19 INFO - 'SHLVL': '1', 14:12:19 INFO - 'TERM': 'linux', 14:12:19 INFO - 'TMOUT': '86400', 14:12:19 INFO - 'USER': 'cltbld', 14:12:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:19 INFO - '_': '/tools/buildbot/bin/python'} 14:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:20 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 14:12:20 INFO - Cleaning up... 14:12:20 INFO - Return code: 0 14:12:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:12:20 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')]} 14:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:20 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')]} 14:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:20 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:20 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>=3.1.1'] in /builds/slave/test/build 14:12:20 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>=3.1.1 14:12:20 INFO - Using env: {'DISPLAY': ':0', 14:12:20 INFO - 'HOME': '/home/cltbld', 14:12:20 INFO - 'LANG': 'en_US.UTF-8', 14:12:20 INFO - 'LANGUAGE': 'en_US:en', 14:12:20 INFO - 'LOGNAME': 'cltbld', 14:12:20 INFO - 'MAIL': '/var/mail/cltbld', 14:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:20 INFO - 'MOZ_NO_REMOTE': '1', 14:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:20 INFO - 'NO_EM_RESTART': '1', 14:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:20 INFO - 'PWD': '/builds/slave/test', 14:12:20 INFO - 'SHELL': '/bin/bash', 14:12:20 INFO - 'SHLVL': '1', 14:12:20 INFO - 'TERM': 'linux', 14:12:20 INFO - 'TMOUT': '86400', 14:12:20 INFO - 'USER': 'cltbld', 14:12:20 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:20 INFO - '_': '/tools/buildbot/bin/python'} 14:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:20 INFO - Downloading/unpacking psutil>=3.1.1 14:12:20 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 14:12:20 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 14:12:20 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 14:12:20 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 14:12:20 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 14:12:20 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 14:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:12:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:22 INFO - Installing collected packages: psutil 14:12:22 INFO - Running setup.py install for psutil 14:12:22 INFO - building 'psutil._psutil_linux' extension 14:12:22 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 14:12:22 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 14:12:22 INFO - building 'psutil._psutil_posix' extension 14:12:22 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 14:12:22 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 14:12:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:23 INFO - Successfully installed psutil 14:12:23 INFO - Cleaning up... 14:12:24 INFO - Return code: 0 14:12:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:12:24 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')]} 14:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:24 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')]} 14:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:24 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:24 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.3'] in /builds/slave/test/build 14:12:24 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.3 14:12:24 INFO - Using env: {'DISPLAY': ':0', 14:12:24 INFO - 'HOME': '/home/cltbld', 14:12:24 INFO - 'LANG': 'en_US.UTF-8', 14:12:24 INFO - 'LANGUAGE': 'en_US:en', 14:12:24 INFO - 'LOGNAME': 'cltbld', 14:12:24 INFO - 'MAIL': '/var/mail/cltbld', 14:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:24 INFO - 'MOZ_NO_REMOTE': '1', 14:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:24 INFO - 'NO_EM_RESTART': '1', 14:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:24 INFO - 'PWD': '/builds/slave/test', 14:12:24 INFO - 'SHELL': '/bin/bash', 14:12:24 INFO - 'SHLVL': '1', 14:12:24 INFO - 'TERM': 'linux', 14:12:24 INFO - 'TMOUT': '86400', 14:12:24 INFO - 'USER': 'cltbld', 14:12:24 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:24 INFO - '_': '/tools/buildbot/bin/python'} 14:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:24 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 14:12:24 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 14:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:12:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:12:26 INFO - Installing collected packages: mozsystemmonitor 14:12:26 INFO - Running setup.py install for mozsystemmonitor 14:12:26 INFO - Successfully installed mozsystemmonitor 14:12:26 INFO - Cleaning up... 14:12:26 INFO - Return code: 0 14:12:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:12:26 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')]} 14:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:26 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')]} 14:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:26 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:26 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==2.5.1'] in /builds/slave/test/build 14:12:26 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==2.5.1 14:12:26 INFO - Using env: {'DISPLAY': ':0', 14:12:26 INFO - 'HOME': '/home/cltbld', 14:12:26 INFO - 'LANG': 'en_US.UTF-8', 14:12:26 INFO - 'LANGUAGE': 'en_US:en', 14:12:26 INFO - 'LOGNAME': 'cltbld', 14:12:26 INFO - 'MAIL': '/var/mail/cltbld', 14:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:26 INFO - 'MOZ_NO_REMOTE': '1', 14:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:26 INFO - 'NO_EM_RESTART': '1', 14:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:26 INFO - 'PWD': '/builds/slave/test', 14:12:26 INFO - 'SHELL': '/bin/bash', 14:12:26 INFO - 'SHLVL': '1', 14:12:26 INFO - 'TERM': 'linux', 14:12:26 INFO - 'TMOUT': '86400', 14:12:26 INFO - 'USER': 'cltbld', 14:12:26 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:26 INFO - '_': '/tools/buildbot/bin/python'} 14:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:26 INFO - Downloading/unpacking jsonschema==2.5.1 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:12:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12:28 INFO - Downloading functools32-3.2.3-2.tar.gz 14:12:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:12:28 INFO - warning: no files found matching '*.txt' 14:12:28 INFO - no previously-included directories found matching 'build' 14:12:28 INFO - no previously-included directories found matching 'dist' 14:12:28 INFO - no previously-included directories found matching '.git*' 14:12:28 INFO - Installing collected packages: jsonschema, functools32 14:12:28 INFO - Running setup.py install for functools32 14:12:28 INFO - warning: no files found matching '*.txt' 14:12:28 INFO - no previously-included directories found matching 'build' 14:12:28 INFO - no previously-included directories found matching 'dist' 14:12:28 INFO - no previously-included directories found matching '.git*' 14:12:28 INFO - Successfully installed jsonschema functools32 14:12:28 INFO - Cleaning up... 14:12:28 INFO - Return code: 0 14:12:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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')]} 14:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:12: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 functools32==3.2.3-2 14:12:28 INFO - Using env: {'DISPLAY': ':0', 14:12:28 INFO - 'HOME': '/home/cltbld', 14:12:28 INFO - 'LANG': 'en_US.UTF-8', 14:12:28 INFO - 'LANGUAGE': 'en_US:en', 14:12:28 INFO - 'LOGNAME': 'cltbld', 14:12:28 INFO - 'MAIL': '/var/mail/cltbld', 14:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:28 INFO - 'MOZ_NO_REMOTE': '1', 14:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:28 INFO - 'NO_EM_RESTART': '1', 14:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:28 INFO - 'PWD': '/builds/slave/test', 14:12:28 INFO - 'SHELL': '/bin/bash', 14:12:28 INFO - 'SHLVL': '1', 14:12:28 INFO - 'TERM': 'linux', 14:12:28 INFO - 'TMOUT': '86400', 14:12:28 INFO - 'USER': 'cltbld', 14:12:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:28 INFO - '_': '/tools/buildbot/bin/python'} 14:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:12:29 INFO - Cleaning up... 14:12:29 INFO - Return code: 0 14:12:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:12:29 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')]} 14:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:29 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')]} 14:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:29 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:29 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 14:12:29 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 14:12:29 INFO - Using env: {'DISPLAY': ':0', 14:12:29 INFO - 'HOME': '/home/cltbld', 14:12:29 INFO - 'LANG': 'en_US.UTF-8', 14:12:29 INFO - 'LANGUAGE': 'en_US:en', 14:12:29 INFO - 'LOGNAME': 'cltbld', 14:12:29 INFO - 'MAIL': '/var/mail/cltbld', 14:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:29 INFO - 'MOZ_NO_REMOTE': '1', 14:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:29 INFO - 'NO_EM_RESTART': '1', 14:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:29 INFO - 'PWD': '/builds/slave/test', 14:12:29 INFO - 'SHELL': '/bin/bash', 14:12:29 INFO - 'SHLVL': '1', 14:12:29 INFO - 'TERM': 'linux', 14:12:29 INFO - 'TMOUT': '86400', 14:12:29 INFO - 'USER': 'cltbld', 14:12:29 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:29 INFO - '_': '/tools/buildbot/bin/python'} 14:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:29 INFO - Downloading/unpacking blobuploader==1.2.4 14:12:29 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 14:12:29 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 14:12:29 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 14:12:29 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 14:12:29 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 14:12:29 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 14:12:31 INFO - Downloading blobuploader-1.2.4.tar.gz 14:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:12:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:12:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 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 14:12:31 INFO - Downloading docopt-0.6.1.tar.gz 14:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:12:32 INFO - Installing collected packages: blobuploader, requests, docopt 14:12:32 INFO - Running setup.py install for blobuploader 14:12:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:12:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:12:32 INFO - Running setup.py install for requests 14:12:32 INFO - Running setup.py install for docopt 14:12:32 INFO - Successfully installed blobuploader requests docopt 14:12:32 INFO - Cleaning up... 14:12:32 INFO - Return code: 0 14:12:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:32 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')]} 14:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:32 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')]} 14:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:32 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:32 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 14:12:32 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 14:12:32 INFO - Using env: {'DISPLAY': ':0', 14:12:32 INFO - 'HOME': '/home/cltbld', 14:12:32 INFO - 'LANG': 'en_US.UTF-8', 14:12:32 INFO - 'LANGUAGE': 'en_US:en', 14:12:32 INFO - 'LOGNAME': 'cltbld', 14:12:32 INFO - 'MAIL': '/var/mail/cltbld', 14:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:32 INFO - 'MOZ_NO_REMOTE': '1', 14:12:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:32 INFO - 'NO_EM_RESTART': '1', 14:12:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:32 INFO - 'PWD': '/builds/slave/test', 14:12:32 INFO - 'SHELL': '/bin/bash', 14:12:32 INFO - 'SHLVL': '1', 14:12:32 INFO - 'TERM': 'linux', 14:12:32 INFO - 'TMOUT': '86400', 14:12:32 INFO - 'USER': 'cltbld', 14:12:32 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:32 INFO - '_': '/tools/buildbot/bin/python'} 14:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:32 INFO - Running setup.py (path:/tmp/pip-M91mMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:32 INFO - Running setup.py (path:/tmp/pip-O2zMV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:32 INFO - Running setup.py (path:/tmp/pip-qRtg2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:33 INFO - Running setup.py (path:/tmp/pip-_Oe485-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:33 INFO - Running setup.py (path:/tmp/pip-kqufs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:33 INFO - Running setup.py (path:/tmp/pip-7q1im6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:33 INFO - Running setup.py (path:/tmp/pip-KpUoCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:33 INFO - Running setup.py (path:/tmp/pip-byYV5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:33 INFO - Running setup.py (path:/tmp/pip-zhtjqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:33 INFO - Running setup.py (path:/tmp/pip-sitnqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:33 INFO - Running setup.py (path:/tmp/pip-hXEWdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:33 INFO - Running setup.py (path:/tmp/pip-_roPMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:33 INFO - Running setup.py (path:/tmp/pip-J6Hk5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:33 INFO - Running setup.py (path:/tmp/pip-GtqGkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:33 INFO - Running setup.py (path:/tmp/pip-QnMy1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:33 INFO - Running setup.py (path:/tmp/pip-QWa2YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:34 INFO - Running setup.py (path:/tmp/pip-qDL9Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:12:34 INFO - Running setup.py install for manifestparser 14:12:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Running setup.py install for mozcrash 14:12:34 INFO - Running setup.py install for mozdebug 14:12:34 INFO - Running setup.py install for mozdevice 14:12:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Running setup.py install for mozfile 14:12:34 INFO - Running setup.py install for mozhttpd 14:12:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Running setup.py install for mozinfo 14:12:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Running setup.py install for mozInstall 14:12:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:12:34 INFO - Running setup.py install for mozleak 14:12:34 INFO - Running setup.py install for mozlog 14:12:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Running setup.py install for moznetwork 14:12:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Running setup.py install for mozprocess 14:12:35 INFO - Running setup.py install for mozprofile 14:12:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Running setup.py install for mozrunner 14:12:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Running setup.py install for mozscreenshot 14:12:35 INFO - Running setup.py install for moztest 14:12:35 INFO - Running setup.py install for mozversion 14:12:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:12:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:12:35 INFO - Cleaning up... 14:12:35 INFO - Return code: 0 14:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:35 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')]} 14:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:35 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')]} 14:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:35 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:35 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 14:12:35 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 14:12:35 INFO - Using env: {'DISPLAY': ':0', 14:12:35 INFO - 'HOME': '/home/cltbld', 14:12:35 INFO - 'LANG': 'en_US.UTF-8', 14:12:35 INFO - 'LANGUAGE': 'en_US:en', 14:12:35 INFO - 'LOGNAME': 'cltbld', 14:12:35 INFO - 'MAIL': '/var/mail/cltbld', 14:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:35 INFO - 'MOZ_NO_REMOTE': '1', 14:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:35 INFO - 'NO_EM_RESTART': '1', 14:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:35 INFO - 'PWD': '/builds/slave/test', 14:12:35 INFO - 'SHELL': '/bin/bash', 14:12:35 INFO - 'SHLVL': '1', 14:12:35 INFO - 'TERM': 'linux', 14:12:35 INFO - 'TMOUT': '86400', 14:12:35 INFO - 'USER': 'cltbld', 14:12:35 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:35 INFO - '_': '/tools/buildbot/bin/python'} 14:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:36 INFO - Running setup.py (path:/tmp/pip-FUnJoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:36 INFO - Running setup.py (path:/tmp/pip-CdmcpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:36 INFO - Running setup.py (path:/tmp/pip-5L7c5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:36 INFO - Running setup.py (path:/tmp/pip-1PIqqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:36 INFO - Running setup.py (path:/tmp/pip-HAK39s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:36 INFO - Running setup.py (path:/tmp/pip-L7kDNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:36 INFO - Running setup.py (path:/tmp/pip-w7W_Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:36 INFO - Running setup.py (path:/tmp/pip-ZXstXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:36 INFO - Running setup.py (path:/tmp/pip-2OpSMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:36 INFO - Running setup.py (path:/tmp/pip-t5ILBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:36 INFO - Running setup.py (path:/tmp/pip-N36I5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:36 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:36 INFO - Running setup.py (path:/tmp/pip-OA95Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 14:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:36 INFO - Running setup.py (path:/tmp/pip-F8GpnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:37 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)) 14:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:37 INFO - Running setup.py (path:/tmp/pip-fWta6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 14:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:37 INFO - Running setup.py (path:/tmp/pip-msz9yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:37 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)) 14:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:37 INFO - Running setup.py (path:/tmp/pip-W3RGD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:37 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)) 14:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:37 INFO - Running setup.py (path:/tmp/pip-Nbh78T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:37 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)) 14:12:37 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)) 14:12:37 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)) 14:12:37 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)) 14:12:37 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)) 14:12:37 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)) 14:12:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:12:37 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 14:12:37 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 14:12:37 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 14:12:37 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 14:12:37 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 14:12:37 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 14:12:39 INFO - Downloading blessings-1.6.tar.gz 14:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:12:39 INFO - Installing collected packages: blessings 14:12:39 INFO - Running setup.py install for blessings 14:12:39 INFO - Successfully installed blessings 14:12:39 INFO - Cleaning up... 14:12:39 INFO - Return code: 0 14:12:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:39 INFO - Reading from file tmpfile_stdout 14:12:39 INFO - Current package versions: 14:12:39 INFO - argparse == 1.2.1 14:12:39 INFO - blessings == 1.6 14:12:39 INFO - blobuploader == 1.2.4 14:12:39 INFO - docopt == 0.6.1 14:12:39 INFO - functools32 == 3.2.3-2 14:12:39 INFO - jsonschema == 2.5.1 14:12:39 INFO - manifestparser == 1.1 14:12:39 INFO - mozInstall == 1.12 14:12:39 INFO - mozcrash == 0.17 14:12:39 INFO - mozdebug == 0.1 14:12:39 INFO - mozdevice == 0.48 14:12:39 INFO - mozfile == 1.2 14:12:39 INFO - mozhttpd == 0.7 14:12:39 INFO - mozinfo == 0.9 14:12:39 INFO - mozleak == 0.1 14:12:39 INFO - mozlog == 3.3 14:12:39 INFO - moznetwork == 0.27 14:12:39 INFO - mozprocess == 0.23 14:12:39 INFO - mozprofile == 0.28 14:12:39 INFO - mozrunner == 6.12 14:12:39 INFO - mozscreenshot == 0.1 14:12:39 INFO - mozsystemmonitor == 0.3 14:12:39 INFO - moztest == 0.7 14:12:39 INFO - mozversion == 1.4 14:12:39 INFO - psutil == 3.1.1 14:12:39 INFO - requests == 1.2.3 14:12:39 INFO - wsgiref == 0.1.2 14:12:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:39 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')]} 14:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:39 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')]} 14:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:39 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:39 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 14:12:39 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 14:12:39 INFO - Using env: {'DISPLAY': ':0', 14:12:39 INFO - 'HOME': '/home/cltbld', 14:12:39 INFO - 'LANG': 'en_US.UTF-8', 14:12:39 INFO - 'LANGUAGE': 'en_US:en', 14:12:39 INFO - 'LOGNAME': 'cltbld', 14:12:39 INFO - 'MAIL': '/var/mail/cltbld', 14:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:39 INFO - 'MOZ_NO_REMOTE': '1', 14:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:39 INFO - 'NO_EM_RESTART': '1', 14:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:39 INFO - 'PWD': '/builds/slave/test', 14:12:39 INFO - 'SHELL': '/bin/bash', 14:12:39 INFO - 'SHLVL': '1', 14:12:39 INFO - 'TERM': 'linux', 14:12:39 INFO - 'TMOUT': '86400', 14:12:39 INFO - 'USER': 'cltbld', 14:12:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:39 INFO - '_': '/tools/buildbot/bin/python'} 14:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 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)) 14:12:39 INFO - Cleaning up... 14:12:39 INFO - Return code: 0 14:12:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:12:39 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')]} 14:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:39 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')]} 14:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:39 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 0x1122030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, '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': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', '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 14:12:39 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 14:12:39 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 14:12:39 INFO - Using env: {'DISPLAY': ':0', 14:12:39 INFO - 'HOME': '/home/cltbld', 14:12:39 INFO - 'LANG': 'en_US.UTF-8', 14:12:39 INFO - 'LANGUAGE': 'en_US:en', 14:12:39 INFO - 'LOGNAME': 'cltbld', 14:12:39 INFO - 'MAIL': '/var/mail/cltbld', 14:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:39 INFO - 'MOZ_NO_REMOTE': '1', 14:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:39 INFO - 'NO_EM_RESTART': '1', 14:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:39 INFO - 'PWD': '/builds/slave/test', 14:12:39 INFO - 'SHELL': '/bin/bash', 14:12:39 INFO - 'SHLVL': '1', 14:12:39 INFO - 'TERM': 'linux', 14:12:39 INFO - 'TMOUT': '86400', 14:12:39 INFO - 'USER': 'cltbld', 14:12:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:39 INFO - '_': '/tools/buildbot/bin/python'} 14:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:12:40 INFO - Cleaning up... 14:12:40 INFO - Return code: 0 14:12:40 INFO - Running post-action listener: _resource_record_post_action 14:12:40 INFO - Running post-action listener: _start_resource_monitoring 14:12:40 INFO - Starting resource monitoring. 14:12:40 INFO - [mozharness: 2016-08-03 21:12:40.142300Z] Finished create-virtualenv step (success) 14:12:40 INFO - [mozharness: 2016-08-03 21:12:40.142827Z] Running install step. 14:12:40 INFO - Running pre-action listener: _resource_record_pre_action 14:12:40 INFO - Running main action method: install 14:12:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:40 INFO - Reading from file tmpfile_stdout 14:12:40 INFO - Detecting whether we're running mozinstall >=1.0... 14:12:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:12:40 INFO - Reading from file tmpfile_stdout 14:12:40 INFO - Output received: 14:12:40 INFO - Usage: mozinstall [options] installer 14:12:40 INFO - Options: 14:12:40 INFO - -h, --help show this help message and exit 14:12:40 INFO - -d DEST, --destination=DEST 14:12:40 INFO - Directory to install application into. [default: 14:12:40 INFO - "/builds/slave/test"] 14:12:40 INFO - --app=APP Application being installed. [default: firefox] 14:12:40 INFO - mkdir: /builds/slave/test/build/application 14:12:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:12:56 INFO - Reading from file tmpfile_stdout 14:12:56 INFO - Output received: 14:12:56 INFO - /builds/slave/test/build/application/firefox/firefox 14:12:56 INFO - Running post-action listener: _resource_record_post_action 14:12:56 INFO - [mozharness: 2016-08-03 21:12:56.430816Z] Finished install step (success) 14:12:56 INFO - [mozharness: 2016-08-03 21:12:56.431091Z] Running run-tests step. 14:12:56 INFO - Running pre-action listener: _resource_record_pre_action 14:12:56 INFO - Running main action method: run_tests 14:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:12:56 INFO - Python 2.7.3 14:12:56 INFO - Return code: 0 14:12:56 INFO - grabbing minidump binary from tooltool 14:12:56 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')]} 14:12:56 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 0x13e13b0>, '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 0x13e22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:12:56 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 14:12:56 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 14:12:56 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 14:12:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:12:56 INFO - Return code: 0 14:12:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:12:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:12:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:12:56 INFO - Using env: {'DISPLAY': ':0', 14:12:56 INFO - 'HOME': '/home/cltbld', 14:12:56 INFO - 'LANG': 'en_US.UTF-8', 14:12:56 INFO - 'LANGUAGE': 'en_US:en', 14:12:56 INFO - 'LOGNAME': 'cltbld', 14:12:56 INFO - 'MAIL': '/var/mail/cltbld', 14:12:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:12:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:56 INFO - 'MOZ_NO_REMOTE': '1', 14:12:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:12:56 INFO - 'NO_EM_RESTART': '1', 14:12:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:56 INFO - 'PWD': '/builds/slave/test', 14:12:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:12:56 INFO - 'SHELL': '/bin/bash', 14:12:56 INFO - 'SHLVL': '1', 14:12:56 INFO - 'TERM': 'linux', 14:12:56 INFO - 'TMOUT': '86400', 14:12:56 INFO - 'USER': 'cltbld', 14:12:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191', 14:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:56 INFO - '_': '/tools/buildbot/bin/python'} 14:12:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:12:56 INFO - mozversion application_buildid: 20160803135021 14:12:56 INFO - mozversion application_changeset: 265e7ad32cbe8749388575c61399ea34c413525b 14:12:56 INFO - mozversion application_display_name: Nightly 14:12:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:12:56 INFO - mozversion application_name: Firefox 14:12:56 INFO - mozversion application_remotingname: firefox 14:12:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:12:56 INFO - mozversion application_vendor: Mozilla 14:12:56 INFO - mozversion application_version: 51.0a1 14:12:56 INFO - mozversion platform_buildid: 20160803135021 14:12:56 INFO - mozversion platform_changeset: 265e7ad32cbe8749388575c61399ea34c413525b 14:12:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:12:56 INFO - mozversion platform_version: 51.0a1 14:12:56 INFO - using testdate: 1470258776 14:12:56 INFO - actual date: 1470258776 14:12:56 INFO - starting webserver on 'localhost:49938' 14:12:56 INFO - SUITE-START | Running 2 tests 14:12:56 INFO - TEST-START | dromaeo_css 14:12:56 INFO - operating with platform_type : linux_ 14:12:56 INFO - Initialising browser for dromaeo_css test... 14:12:56 INFO - TEST-INFO | started process 4221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6lIgi3/profile http://localhost:49938/getInfo.html) 14:13:00 INFO - PROCESS | 4221 | __metrics Screen width/height:1600/1200 14:13:00 INFO - PROCESS | 4221 | colorDepth:24 14:13:00 INFO - PROCESS | 4221 | Browser inner width/height: 1024/697 14:13:00 INFO - PROCESS | 4221 | __metrics 14:13:01 INFO - PROCESS | 4221 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:01 INFO - PROCESS | 4221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:02 INFO - TEST-INFO | 4221: exit 0 14:13:02 INFO - Browser initialized. 14:13:02 INFO - Running cycle 1/1 for dromaeo_css test... 14:13:02 INFO - TEST-INFO | started process 4300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6lIgi3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:14 INFO - PROCESS | 4300 | RSS: Main: 156901376 14:13:14 INFO - PROCESS | 4300 | 14:14:06 INFO - PROCESS | 4300 | 1470258846522 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] 14:16:18 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49938/tests/dromaeo/cssquery-ext.html) 14:16:19 INFO - PROCESS | 4300 | RSS: Main: 244994048 14:16:19 INFO - PROCESS | 4300 | 14:19:22 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-ext.html (next: http://localhost:49938/tests/dromaeo/cssquery-jquery.html) 14:19:22 INFO - PROCESS | 4300 | RSS: Main: 247123968 14:19:22 INFO - PROCESS | 4300 | 14:22:25 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49938/tests/dromaeo/cssquery-mootools.html) 14:22:25 INFO - PROCESS | 4300 | RSS: Main: 189001728 14:22:25 INFO - PROCESS | 4300 | 14:25:27 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49938/tests/dromaeo/cssquery-prototype.html) 14:25:28 INFO - PROCESS | 4300 | RSS: Main: 213196800 14:25:28 INFO - PROCESS | 4300 | 14:28:31 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49938/tests/dromaeo/cssquery-yui.html) 14:28:32 INFO - PROCESS | 4300 | RSS: Main: 189423616 14:28:32 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | Cycle 1(1): loaded http://localhost:49938/tests/dromaeo/cssquery-yui.html (next: http://localhost:49938/tests/dromaeo/cssquery-dojo.html) 14:31:35 INFO - PROCESS | 4300 | RSS: Main: 283914240 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | __start_tp_report 14:31:35 INFO - PROCESS | 4300 | _x_x_mozilla_page_load 14:31:35 INFO - PROCESS | 4300 | _x_x_mozilla_page_load_details 14:31:35 INFO - PROCESS | 4300 | |i|pagename|runs| 14:31:35 INFO - PROCESS | 4300 | |0;dojo.html;2607.100550310143;2627.251233398482;2649.854258015809;2671.4389978104596;2672.652555167829;4598.149342371664;4633.212353899837;4732.219183834667;4798.520147985204;4859.586935110516;4674.392328997225;4674.532546745324;4707.035057813148;4736.763161841911;4776.283557466381;4391.27543955247;4430.4683437987405;4438.689291749579;4457.554244575549;4540.7502587357685;4364.803583838735;4509.526499717532;4526.841560545382;4543.4547854257535;4581.198290299201;4217.430646862665;4271.487421509408;4379.189849877783;4428.645708343357;4449.42157519522;4335.393044973706;4426.911461770746;4428.202923473774;4455.844045458393;4506.6169375602785;4408.030233348658;4411.404460397837;4417.779111044467;4456.153330867125;4460.085682435093;4536.795844186987;4777.450593181759;4787.952120478821;4807;4823.13183626947;5110.514501122388;5146.639735218536;5168.7415629218385;5213.895722085575;5225.738713064369;5213.061648903196;5219.5041471060185;5245.711485868275;5283.894322113575;5291.629585928987;4591.816327346935;4902.730349830758;4909.2881532177635;4912.410510738734;4913.975430122862;5248.239005344204;5284.07528682479;5289.127293996525;5405.70268635221;5407.053765591006;5116.744162791845;5130.9230361544605;5133.435322114565;5176.301199338041;5180.559652429511;4940.975295123573;5193.636445448858;5208.374995000624;5254.369475662945;5254.7635356409055;5028.698278103326;5060.139776238048;5086.516780905882;5115.309433226541;5167.302414174113;5087.6947383156385;5091.643584949092;5097;5103.055934652101;5142.640015198975;4093.6929730269753;4285.657147428198;4295.677824163137;4320.330348795942;4367.825286988548;3678.9080272992855;3751.174741556853;3761.6990640749223;3808.8286027128847;3838.1747924196434;4473.843415480443;4475;4475.037866858641;4488.3940668010055;4517.728936263835;5152.562032227228;5194.6104042196985;5201.947980520146;5206.609504287194;5249.081410753103;4200.600942910419;4397.07661391105;4436.179306828182;4452.732836029783;4470.329450582373;4673.112108699336;4674.836380726658;4676.462206846256;4711.387519622495;4714.198586240347;4863;4869.464358920445;4871.123197824426;4875.219964805685;4926.187179115406;4961.330220420341;5092;5125.974370128126;5147.845564633067;5187.455317191791;4845.224764037737;4856.39295088114;4865.513448655106;4897.216445368724;4909.263610458317;5059.418166910852;5065.8733531662;5065.898682026413;5069.5944324454695;5112.795488180431;4901.975490122527;4969.552740253394;4992.425871024879;5026.246063090565;5165.837686520653;4915.26271059344;4915.778789954532;4916.360873086476;4919;4930.975345123395;4892.8532144035735;4895.828645997373;4896.143174944442;4925.679830810986;4945.950540494549;4695.154872122914;4833.589144922721;4892.412910450626;4942.20924652054;4979.900401992012;5092.898142037213;5121.871953201051;5124.359455068116;5126.948730512647;5132.563732082665;4986.351774269467;4988.600911926965;4995.525425084611;4998.625103117353;5035.395752509722 14:31:35 INFO - PROCESS | 4300 | |1;ext.html;553.3691591585592;564.303085689174;569.1349149293075;578.3118089473529;587.5886879184571;30436.69563304369;42900.9969302149;44236.115277694465;44237.557624423745;44364.11271774563;41131;41131.38302925458;41143.58856411435;41545.584544154546;41567.37648935268;7637.656305466254;7783.377329813629;7787.493812902143;7789.4157938154585;7832.334251588622;7770.222977702213;7849.411294152933;7856.528608283521;7862.410319226053;7866.292033716964;7840.960795196044;7882.566458844762;7888.921110788876;7893.368530517573;7925.128235894047;7872.212778722111;7890.250426209501;7898.881516777253;7905.565193914333;7909.446338756289;7751.534907905488;7814.296713295831;7819.452638315263;7827.6477558510005;7827.882581761278;38190.80904595487;38365.808170959244;38414.61585384138;38518.614813852066;38537.421938670945;3145.7011583899493;3187.824669643169;3196.5844440222622;3338.1988322802595;3462.290230502741;29418.852905735548;29690.851545742134;29705.10884673463;29929.251768705737;30945.845270773727;3120.687931206884;3151.952720709191;3169.2710676544525;3237.951430728541;3249.4151052810485;257668;258912.9999999981;259638;261316;261923;31828.522572161437;53136.73431632817;53701.1944820828;53761;53787.462125379025;32438;33447.498287525705;33495.83252083724;33534;33590;14028.929855350862;14167.362468688934;14172.645683857985;14193.14841109516;14194.574162775134;11533.88466115328;11540.711482213012;11567.074634029257;11625.069994400428;11654.067674586013;7583.810404739816;7612.8477430452185;7673.079230492265;7679.462437629424;7729.381649468029;3022.2897619059504;3027.3793872256133;3052.648945371266;3066.6626671066165;3072.6620071792086;918.7473444802732;936.3305236755753;941.275218082073;959.5729900194486;981.4307701533237;27744.306392340353;27891.442171156465;28802;28875;28935;805.5810978291256;934.7709811096325;977.9804403911882;1004.2116938203475;1023.6519583341551;13905.582832515041;14586.19775912335;14733.263336833115;14954;14954.626134346729;369023;372921;373331;373856;374528;50290.74854625703;50692;50715.7464212684;50932;50940.49059509357;1007.7732510185297;1057.5241141486363;1161.1523587780798;1182.3437991914398;1206.6500714792612;25793.87103064473;28997.565036524047;29403.705962940952;29497.557536636537;29806.442582631786;47409;48196;48259.75870120627;48269.5173048265;48317.27524087071;275711;276726;276756;277898;278200;25221.873890630428;25981.22056338313;26119.869400653635;26624.8668756655;39283;41549;41596;42049.369259461746;42101.578984209766;43177.35233971556;35574.82212588921;36051.27897441984;36356.454653179695;36910.26179476449;36969.44545831868;36125.458118127724;36198.063745457664;36234.637653623126;37679;37826.810865945496;35489.29021419609;35794.463083053255;35939.101522461104;36160.63839361678;36489.81755091208;30871.536926946563;32408.513872291463;32418.513722293712;32550.674493254763;32881.67118328786;24886.875565622056;25111.623325650497;25514.362140946625;25600.743992559837;25720.61419078678 14:31:35 INFO - PROCESS | 4300 | |2;jquery.html;332.808635034855;350.48653722296837;353.15067263231936;355.8718861209964;374.55053935277675;12917.741645167103;16369.42707005255;16611.169441527934;16694.08182549961;17527.298908043664;15028.549143525684;15163.696726065473;15897.602559936007;16013.519594412186;16074.59813504663;4400;5044.798208071682;5159.355080614923;5208.348956380452;5258.737063146847;3631.7457777955556;3805.143842635399;5143.434222235551;5688.687122208277;6100.328963813977;6027.126066720323;6120.540959428038;6143.416375444348;6161.229846269216;6164.691765411734;5830.562707796911;6009.369016253296;6025.668588227646;6035.155078288999;6035.698215089228;5277.340332458442;5278.656887302313;5286.259923610698;5295.576353891679;6407.87184256317;22177.556448871095;23357.41606459835;23764.04943802246;23824.523509529885;24341.756582434125;2086.812186903186;2180.008096316172;2284.383216531544;2446.7797898189247;2492.2523243027017;13288.73422531544;14517.346719397652;15245.16151611672;15424.845751542565;15709.214539273105;1888.0748433267738;1896.1183049881733;1898.8196121368449;1909.847212223019;1930.7683078030586;81572;86449;88442;88637.55681221552;88904.5554772222;16534.83465165357;16644.916775416044;16652.250648720837;16674.166291685367;17602.207900644502;29321.413571728444;29467.263318416783;29528.852355738512;29546.261343466584;29557.408851822842;8546.786330341667;8674.349423793241;8739.033717413658;8873.866891996626;8923.687670931486;7257.92742072583;7330.6701198445135;7791.649375778104;8024.719134830253;8026.277635012877;8328.66685332592;8443.28232100266;8452.746417607359;8634.136586341441;8666.263367613697;2731.6721993360925;3073.3853229354218;3114.0813460029344;3204.6154461464307;3306.487494438366;661.8974059020859;773.7949443397504;828.2959484438179;915.3043686798081;969.2972594868663;16164.233939032772;16516.256768445448;16983.915080424766;17134.742978855327;17191.742123868153;737.3953358246187;833.7873842170208;866.8873046503915;871.0070519607652;923.2014307623991;7256;9188.26493880488;9442.527873606292;9525.809483810424;9547.61809527625;411474;418620;435135;436022;438313;78487.60756196182;79306.20693793104;79605;80091.19908800954;82995.17004829996;892.2683399612256;911.3165126155384;917.1333090229803;917.655879045358;920.3143657974717;14376.137431754129;14735.410583576537;14958.980537627282;14993.500649934918;15035.699286014436;35375.10115592524;35451.46822797608;35787.82106089453;35843.64156358403;36346.8182659085;51499.48500514947;51607.225891610906;51894.22158667548;52036.219456707426;52315.47684523106;7339.59632220233;7583.620818959141;7612.391008719178;7737.961310193413;7853.725119620786;6162.845928851814;6821.658917054028;9089.863652045093;10749.19381046409;10894.291871028357;9528.28537859677;9582.137607615348;9797;9838.704838854846;9899.208063355058;9701.126898579161;9874.901250987685;9881.061299176567;9954.452505111998;10088.142507886912;9318;9395.577199025924;9580.185684216642;9915.702528924145;10436.947815260875;7335.156457007299;7335.193128755725;7425.368843648351;7574.886376704464;7674.961625191839;7341.706331746884;9092.318076144447;9692.127708506268;9818.165455936325;10036.598536058767 14:31:35 INFO - PROCESS | 4300 | |3;mootools.html;1351.3513513513512;1387.687770251693;1429.3639330497926;1625.7154997875384;1659.502149355193;6671.933280667192;7206.927930720692;7216.025836512063;7222.133343998715;7227.20500744919;6099.57302988791;6295.527835412351;6720.9663951680295;6948.235694073661;7095.077639906818;2099.7900209979034;2123.044629916536;2124.5326028273753;2125.1818050050765;2127.723395958531;2052.6407878621258;2088.749350077993;2139.860909040915;2143.9249626263086;2147.4309308033385;1799.991000044998;2058.5368292134226;2131.9253826116087;2138.1874887542713;2153.3862849088014;2103.463616777712;2116.523782149012;2127.4681329667583;2131.626965281085;2134.1676746069047;2115.375964090597;2117.163720330461;2120.067170444999;2122.1617461102933;2124.0866427436194;7244.058272424604;7244.985702001724;7284.74503392384;7293.124825021031;7305.963470182615;4048.8987775305854;4103.815328310204;4153.376993450976;4153.501579810412;4158.313878210122;2726.1412655013805;2849.957250641242;2880.8559572021527;2885.0335137728885;2885.6970018148004;929.7907970706536;977.6773664690635;987.7086259553378;994.462989985407;1002.8094662014194;9491.288153388523;9546.474943878084;9546.474943878084;9556.952215238949;9558.235341172758;3743.1765011697385;3855.9421608675893;3950.9604903951167;4016.136530645925;4018.578049304839;8248.8350232995;8249.463784854086;8251.95874020638;8261.256486916087;8264.710735124241;4260.2970509866145;4262.232798096373;4263.893402664959;4263.978680106579;4264.317709166518;3859.459675645412;3868.9613103869165;3872.244912242142;3875.6318149775725;3884.786336751449;3245.7727959043114;3344.7324214062814;3375.8312084395684;3419.26485805553;3423.1613254752747;1009.7526106103904;1012.6860673191334;1016.7305663998899;1028.7068185567077;1037.906588407047;651.6741629185407;730.1292071469567;843.6878355008686;861.5175501719058;869.860822268434;4872.244802055687;4991.625628077836;4998.400191976986;5143.048536020773;5213.921791173136;821.1747194069922;845.9365547583833;849.1890244816186;883.3595643158787;909.8498747706686;3229.838508074587;3399.6940275375337;3417.9316413671586;3420.589529256505;3427.6572342765526;8189.303909167668;8696.565171741388;8697.521636310064;8711.21599056088;8719.215270625675;8493.915060849313;8640.222379985706;8662.6101825418;8668.653253869774;8679.088695686989;6684;6848.246692863781;6864.897026544606;6867.519273650796;6885.070515480446;4764.33299338086;4800.711957282574;4854.320395144612;4919.63102767301;4950.183219768698;8063.8790418144945;8101.635426405708;8144.900438440734;8185.672573097009;8232.135625759209;8295.170482951657;8299.543525106177;8377.790555236168;8377.874331884905;8407.285380742705;5891.086881533369;6052.727627256872;6055.212822333068;6055.333913269449;6058;6805.625690587065;6816.079829222991;6875.4155896749335;6876.931230687629;6891.793246202622;4722.811087556459;6156.045812899008;6168.691565421801;6170.290416601969;6184.196054512884;6154.169187159855;6195.256569211543;6227.844303892439;6374.776882809265;6416.165898433173;5670.234518340137;5775.960327141155;5775.971120144372;5776.768929242783;5781.913271300849;4130.876073717794;4144.79276036203;4145.81343839534;4252.851150209728;4451.399061126706;4330.285502891988;4354.804033818423;4366.781660917005;4490.326451032372;4508.18852606534 14:31:35 INFO - PROCESS | 4300 | |4;prototype.html;289.0936912778439;294.16017270692174;300.95034319337304;301.3490859742956;303.0650443382166;4772.379590653214;4817.373741413611;4829.830955916544;4908.938245936706;4909.116359055369;4503.662225333097;4627.699199552027;4704.647151463637;4769.165396055694;4770.260609605517;3792.924141517168;3799.3920972644382;3805.486259354979;3809.4857194278834;3813.275477659236;3808.7524310919703;3814.9237015259678;3820.4460353248764;3826.6747326477143;3827.5215598050245;3581.3374525712698;3651.963480365202;3756.661900428961;3762.887113386589;3784.489093972306;3756.1736417988;3756.4740936268945;3758.4174452959696;3762.5108735864437;3766.60450652683;3311.420501412267;3331.1006028372444;3332.100332910125;3340.8997730067895;3346.297277571713;4437.733735975852;4625.560571745679;4629.305604159403;4632.698874573142;4636.35091087248;4212.894677633084;4216.47294088239;4227.87316380509;4233.23801715691;4236;4991.750412479361;4992.425871024806;4996.500349964975;5006.774695138727;5018.47306032865;3529.4176460883923;3546.202104526487;3550.431930891071;3554.431290993429;3556.3065202285566;4913.656044076916;5137.640365174402;5171.017506673757;5186.40356359016;5199.66202196856;3579.409397449444;3601.657842504958;3601.8019008954757;3604.7563590561213;3620.244181571988;3671.2473942841652;3672.247189326182;3672.8714494992546;3675.963240367562;3676.7499050322485;3982.3628219484744;4194.203101410722;4202.558731333227;4203.285441474956;4205.201011807747;4972.602191824645;5003.799848006039;5006.148954677714;5009.373828271466;5031;1262.4697626996683;1271.1419791640606;1275.6491964709778;1276.049343239274;1279.6883194725294;813.2883726739103;846.6401779243797;865.9437136586101;874.2874557235959;875.9474431534128;4427.335899615019;4427.690061695715;4433.733975961452;4434.467863856358;4442.244818380883;5111.7699703513435;5113.181890897438;5115.283860259567;5119.513646203604;5120.564751996122;4315.8489452869;4342.177584570055;4374.27824408969;4425.491068527096;4430.911381772346;2686.3210493441597;2699.689535703419;2699.91900242993;2704.7700945419465;2708.3229192701824;4790.209615413488;4792.856214313576;4806.49531799156;4847.321375007502;4855.36880205571;4904.033228216258;5151.742412879416;5208.869778255498;5215.765290561858;5243.580513558906;4790.634669119424;4794.712317261045;4794.904101917942;4801;4814.975925120352;4991.9500804991485;5071.833478299938;5084.41529224144;5093.567046801063;5102.158143906362;4829.637777166657;4981.452040275639;4990.38005047353;5071.7464126792775;5095.694258344512;4866.343043689057;4870.975645121752;4904.6566740327835;4907.68100073494;4912.606991440604;3606.9819650901577;3624.800635965047;3625.7643253189513;3633.5276414066;3641.198936234024;5026.773795179152;5139;5155.407128180307;5182.507661772125;5185.559227465707;4987.458904177929;5038.294638750651;5055.898882022412;5068.9239661405845;5072.188449848007;5052.418971818288;5062.190049592047;5063.215201643751;5064.721440320818;5083.87290317745;4787.928181077217;4949.82675606352;4991.825286114969;5001.599872010303;5005.274235235797;4858.78135483897;5000.749962501933;5065.468125846733;5073.568746656428;5115.053715062725;4969.279454479152;4969.503049695001;4970.403551573834;4973.825916092919;4975.154223782039 14:31:35 INFO - PROCESS | 4300 | |5;yui.html;624.4380057947847;637.0889627832204;642.9678516074198;680.2109552918612;739.4601940583374;11863;12892.548760793374;12900.806487902677;12918.354082295871;12936.159149655266;12722.809157862639;12835.101542891978;12865.5497057603;12916.095873288874;12923.159994600344;628.7799270255397;640.4268179978916;753.8793792993122;771.1555846348253;776.130733578393;785.8271180340345;786.5831109511968;790.4506368074191;790.8260182759784;791.1416113516841;838.9370797190205;839.8698201778703;840.4117118017382;841.5161282262693;843.2031730015138;662.8309781005861;802.5265093594749;825.7976795685036;826.8263664630465;827.1067247372823;608.2822269721727;609.6768712582294;611.2939554814196;611.5474548833876;611.8011646214998;11717.882821171765;11737.647870563897;11766.823497647456;12032.217905836093;12085.45615447307;11756.23584467007;11953.103517236243;12084;12278.754424911542;13273.13724607907;12177.878221217763;12193.512259509609;12277.631671049883;12467.563635272812;12738.80891786624;3771.208046310278;3788.2612890486366;3852.5569559500736;3945.6251656092627;3965.801709914522;69503.65248173778;70444.6477767613;70710.64644676795;71789.28210717878;71824.64087679528;9728.708138755848;9980.550875210633;10069.597216111273;10114.494275286206;10360;18818.90590547066;19106.52233694141;19139.2344306229;19165;19182.616347672967;7065.89401158983;7132.786016419515;7289;7400.9629951850975;7547.471676982558;3656.689181419556;3743.981280093582;3818.236352729465;3823.330917089498;3952.446657467957;2809.789265805073;2922.1165279429333;2923.327634644044;2933.3693255950075;2953.822770633769;522.2949018824633;524.5750941737128;528.118042868403;532.1804421191322;539.4578448659073;551.283331668829;563.9069553523705;582.4263100845664;587.7825204674213;591.104476717773;7949.7217597383815;8102.675892964333;8183.263506284463;8222.342212623096;8264.669413223404;516.3597139546935;580.3760956971273;588.3586890289605;620.807549659598;630.3318482408662;3738.345789486829;3745;3828.406596977473;3883.3010058189234;4013.2976729072298;105706;106176;106368;111910;112852;90414;90801;92264.0773592269;94189.5290523543;97351;50353.74823125861;82074.58962705148;85263.57368213119;85556.99999999875;86121.13878861383;7238.674259658434;7261.201267860425;7531.3974882008215;7689.807754806175;7856.096548896721;44619.98420142073;45209.547904520536;45587;47527.52472475231;48668.513314866395;46019;46210;63178.36821631725;64188.679056604415;67427.56014157923;11819.763604727685;12145.271283723005;12508.124431289722;12508.312042837731;13105.868941310466;12214;12546.435410406371;12790.104692671795;13075.738485230433;13559.525416610371;10348.327358721659;10758.246922715334;11062;11115.055220306365;11149.386783726974;10559.72965812354;10651.30766500206;10816.296940699143;10834;11034.393108379116;10716.892831071902;11145.108391328513;11554.364509951698;11579.88420115788;11657.591984280509;8775.36152333801;9359.80591176902;9363;9428.198603118812;9720.805583888425;9201.861972070557;9661.178799802095;9766.75583110428;9771.364861283993;9804.117629413387 14:31:35 INFO - PROCESS | 4300 | __end_tp_report 14:31:35 INFO - PROCESS | 4300 | __start_cc_report 14:31:35 INFO - PROCESS | 4300 | _x_x_mozilla_cycle_collect,994 14:31:35 INFO - PROCESS | 4300 | __end_cc_report 14:31:35 INFO - PROCESS | 4300 | __startTimestamp1470259895919__endTimestamp 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | ------- Summary: start ------- 14:31:35 INFO - PROCESS | 4300 | Number of tests: 6 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#0] dojo.html Cycles:165 Average:4747.93 Median:4895.83 stddev:512.71 (10.5%) stddev-sans-first:486.00 14:31:35 INFO - PROCESS | 4300 | Values: 2607.1 2627.3 2649.9 2671.4 2672.7 4598.1 4633.2 4732.2 4798.5 4859.6 4674.4 4674.5 4707.0 4736.8 4776.3 4391.3 4430.5 4438.7 4457.6 4540.8 4364.8 4509.5 4526.8 4543.5 4581.2 4217.4 4271.5 4379.2 4428.6 4449.4 4335.4 4426.9 4428.2 4455.8 4506.6 4408.0 4411.4 4417.8 4456.2 4460.1 4536.8 4777.5 4788.0 4807.0 4823.1 5110.5 5146.6 5168.7 5213.9 5225.7 5213.1 5219.5 5245.7 5283.9 5291.6 4591.8 4902.7 4909.3 4912.4 4914.0 5248.2 5284.1 5289.1 5405.7 5407.1 5116.7 5130.9 5133.4 5176.3 5180.6 4941.0 5193.6 5208.4 5254.4 5254.8 5028.7 5060.1 5086.5 5115.3 5167.3 5087.7 5091.6 5097.0 5103.1 5142.6 4093.7 4285.7 4295.7 4320.3 4367.8 3678.9 3751.2 3761.7 3808.8 3838.2 4473.8 4475.0 4475.0 4488.4 4517.7 5152.6 5194.6 5201.9 5206.6 5249.1 4200.6 4397.1 4436.2 4452.7 4470.3 4673.1 4674.8 4676.5 4711.4 4714.2 4863.0 4869.5 4871.1 4875.2 4926.2 4961.3 5092.0 5126.0 5147.8 5187.5 4845.2 4856.4 4865.5 4897.2 4909.3 5059.4 5065.9 5065.9 5069.6 5112.8 4902.0 4969.6 4992.4 5026.2 5165.8 4915.3 4915.8 4916.4 4919.0 4931.0 4892.9 4895.8 4896.1 4925.7 4946.0 4695.2 4833.6 4892.4 4942.2 4979.9 5092.9 5121.9 5124.4 5126.9 5132.6 4986.4 4988.6 4995.5 4998.6 5035.4 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#1] ext.html Cycles:180 Average:45468.36 Median:36216.35 stddev:80843.72 (223.2%) stddev-sans-first:81000.17 14:31:35 INFO - PROCESS | 4300 | Values: 553.4 564.3 569.1 578.3 587.6 30436.7 42901.0 44236.1 44237.6 44364.1 41131.0 41131.4 41143.6 41545.6 41567.4 7637.7 7783.4 7787.5 7789.4 7832.3 7770.2 7849.4 7856.5 7862.4 7866.3 7841.0 7882.6 7888.9 7893.4 7925.1 7872.2 7890.3 7898.9 7905.6 7909.4 7751.5 7814.3 7819.5 7827.6 7827.9 38190.8 38365.8 38414.6 38518.6 38537.4 3145.7 3187.8 3196.6 3338.2 3462.3 29418.9 29690.9 29705.1 29929.3 30945.8 3120.7 3152.0 3169.3 3238.0 3249.4 257668.0 258913.0 259638.0 261316.0 261923.0 31828.5 53136.7 53701.2 53761.0 53787.5 32438.0 33447.5 33495.8 33534.0 33590.0 14028.9 14167.4 14172.6 14193.1 14194.6 11533.9 11540.7 11567.1 11625.1 11654.1 7583.8 7612.8 7673.1 7679.5 7729.4 3022.3 3027.4 3052.6 3066.7 3072.7 918.7 936.3 941.3 959.6 981.4 27744.3 27891.4 28802.0 28875.0 28935.0 805.6 934.8 978.0 1004.2 1023.7 13905.6 14586.2 14733.3 14954.0 14954.6 369023.0 372921.0 373331.0 373856.0 374528.0 50290.7 50692.0 50715.7 50932.0 50940.5 1007.8 1057.5 1161.2 1182.3 1206.7 25793.9 28997.6 29403.7 29497.6 29806.4 47409.0 48196.0 48259.8 48269.5 48317.3 275711.0 276726.0 276756.0 277898.0 278200.0 25221.9 25981.2 26119.9 26624.9 39283.0 41549.0 41596.0 42049.4 42101.6 43177.4 35574.8 36051.3 36356.5 36910.3 36969.4 36125.5 36198.1 36234.6 37679.0 37826.8 35489.3 35794.5 35939.1 36160.6 36489.8 30871.5 32408.5 32418.5 32550.7 32881.7 24886.9 25111.6 25514.4 25600.7 25720.6 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#2] jquery.html Cycles:180 Average:26789.49 Median:5290.92 stddev:70766.50 (1337.5%) stddev-sans-first:70937.14 14:31:35 INFO - PROCESS | 4300 | Values: 332.8 350.5 353.2 355.9 374.6 12917.7 16369.4 16611.2 16694.1 17527.3 15028.5 15163.7 15897.6 16013.5 16074.6 4400.0 5044.8 5159.4 5208.3 5258.7 3631.7 3805.1 5143.4 5688.7 6100.3 6027.1 6120.5 6143.4 6161.2 6164.7 5830.6 6009.4 6025.7 6035.2 6035.7 5277.3 5278.7 5286.3 5295.6 6407.9 22177.6 23357.4 23764.0 23824.5 24341.8 2086.8 2180.0 2284.4 2446.8 2492.3 13288.7 14517.3 15245.2 15424.8 15709.2 1888.1 1896.1 1898.8 1909.8 1930.8 81572.0 86449.0 88442.0 88637.6 88904.6 16534.8 16644.9 16652.3 16674.2 17602.2 29321.4 29467.3 29528.9 29546.3 29557.4 8546.8 8674.3 8739.0 8873.9 8923.7 7257.9 7330.7 7791.6 8024.7 8026.3 8328.7 8443.3 8452.7 8634.1 8666.3 2731.7 3073.4 3114.1 3204.6 3306.5 661.9 773.8 828.3 915.3 969.3 16164.2 16516.3 16983.9 17134.7 17191.7 737.4 833.8 866.9 871.0 923.2 7256.0 9188.3 9442.5 9525.8 9547.6 411474.0 418620.0 435135.0 436022.0 438313.0 78487.6 79306.2 79605.0 80091.2 82995.2 892.3 911.3 917.1 917.7 920.3 14376.1 14735.4 14959.0 14993.5 15035.7 35375.1 35451.5 35787.8 35843.6 36346.8 51499.5 51607.2 51894.2 52036.2 52315.5 7339.6 7583.6 7612.4 7738.0 7853.7 6162.8 6821.7 9089.9 10749.2 10894.3 9528.3 9582.1 9797.0 9838.7 9899.2 9701.1 9874.9 9881.1 9954.5 10088.1 9318.0 9395.6 9580.2 9915.7 10436.9 7335.2 7335.2 7425.4 7574.9 7675.0 7341.7 9092.3 9692.1 9818.2 10036.6 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#3] mootools.html Cycles:180 Average:4725.87 Median:5178.49 stddev:2563.55 (49.5%) stddev-sans-first:2558.19 14:31:35 INFO - PROCESS | 4300 | Values: 1351.4 1387.7 1429.4 1625.7 1659.5 6671.9 7206.9 7216.0 7222.1 7227.2 6099.6 6295.5 6721.0 6948.2 7095.1 2099.8 2123.0 2124.5 2125.2 2127.7 2052.6 2088.7 2139.9 2143.9 2147.4 1800.0 2058.5 2131.9 2138.2 2153.4 2103.5 2116.5 2127.5 2131.6 2134.2 2115.4 2117.2 2120.1 2122.2 2124.1 7244.1 7245.0 7284.7 7293.1 7306.0 4048.9 4103.8 4153.4 4153.5 4158.3 2726.1 2850.0 2880.9 2885.0 2885.7 929.8 977.7 987.7 994.5 1002.8 9491.3 9546.5 9546.5 9557.0 9558.2 3743.2 3855.9 3951.0 4016.1 4018.6 8248.8 8249.5 8252.0 8261.3 8264.7 4260.3 4262.2 4263.9 4264.0 4264.3 3859.5 3869.0 3872.2 3875.6 3884.8 3245.8 3344.7 3375.8 3419.3 3423.2 1009.8 1012.7 1016.7 1028.7 1037.9 651.7 730.1 843.7 861.5 869.9 4872.2 4991.6 4998.4 5143.0 5213.9 821.2 845.9 849.2 883.4 909.8 3229.8 3399.7 3417.9 3420.6 3427.7 8189.3 8696.6 8697.5 8711.2 8719.2 8493.9 8640.2 8662.6 8668.7 8679.1 6684.0 6848.2 6864.9 6867.5 6885.1 4764.3 4800.7 4854.3 4919.6 4950.2 8063.9 8101.6 8144.9 8185.7 8232.1 8295.2 8299.5 8377.8 8377.9 8407.3 5891.1 6052.7 6055.2 6055.3 6058.0 6805.6 6816.1 6875.4 6876.9 6891.8 4722.8 6156.0 6168.7 6170.3 6184.2 6154.2 6195.3 6227.8 6374.8 6416.2 5670.2 5776.0 5776.0 5776.8 5781.9 4130.9 4144.8 4145.8 4252.9 4451.4 4330.3 4354.8 4366.8 4490.3 4508.2 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#4] prototype.html Cycles:180 Average:4151.60 Median:4780.15 stddev:1201.25 (25.1%) stddev-sans-first:1169.11 14:31:35 INFO - PROCESS | 4300 | Values: 289.1 294.2 301.0 301.3 303.1 4772.4 4817.4 4829.8 4908.9 4909.1 4503.7 4627.7 4704.6 4769.2 4770.3 3792.9 3799.4 3805.5 3809.5 3813.3 3808.8 3814.9 3820.4 3826.7 3827.5 3581.3 3652.0 3756.7 3762.9 3784.5 3756.2 3756.5 3758.4 3762.5 3766.6 3311.4 3331.1 3332.1 3340.9 3346.3 4437.7 4625.6 4629.3 4632.7 4636.4 4212.9 4216.5 4227.9 4233.2 4236.0 4991.8 4992.4 4996.5 5006.8 5018.5 3529.4 3546.2 3550.4 3554.4 3556.3 4913.7 5137.6 5171.0 5186.4 5199.7 3579.4 3601.7 3601.8 3604.8 3620.2 3671.2 3672.2 3672.9 3676.0 3676.7 3982.4 4194.2 4202.6 4203.3 4205.2 4972.6 5003.8 5006.1 5009.4 5031.0 1262.5 1271.1 1275.6 1276.0 1279.7 813.3 846.6 865.9 874.3 875.9 4427.3 4427.7 4433.7 4434.5 4442.2 5111.8 5113.2 5115.3 5119.5 5120.6 4315.8 4342.2 4374.3 4425.5 4430.9 2686.3 2699.7 2699.9 2704.8 2708.3 4790.2 4792.9 4806.5 4847.3 4855.4 4904.0 5151.7 5208.9 5215.8 5243.6 4790.6 4794.7 4794.9 4801.0 4815.0 4992.0 5071.8 5084.4 5093.6 5102.2 4829.6 4981.5 4990.4 5071.7 5095.7 4866.3 4871.0 4904.7 4907.7 4912.6 3607.0 3624.8 3625.8 3633.5 3641.2 5026.8 5139.0 5155.4 5182.5 5185.6 4987.5 5038.3 5055.9 5068.9 5072.2 5052.4 5062.2 5063.2 5064.7 5083.9 4787.9 4949.8 4991.8 5001.6 5005.3 4858.8 5000.7 5065.5 5073.6 5115.1 4969.3 4969.5 4970.4 4973.8 4975.2 14:31:35 INFO - PROCESS | 4300 | 14:31:35 INFO - PROCESS | 4300 | [#5] yui.html Cycles:180 Average:18498.36 Median:25435.05 stddev:27605.14 (108.5%) stddev-sans-first:27649.95 14:31:35 INFO - PROCESS | 4300 | Values: 624.4 637.1 643.0 680.2 739.5 11863.0 12892.5 12900.8 12918.4 12936.2 12722.8 12835.1 12865.5 12916.1 12923.2 628.8 640.4 753.9 771.2 776.1 785.8 786.6 790.5 790.8 791.1 838.9 839.9 840.4 841.5 843.2 662.8 802.5 825.8 826.8 827.1 608.3 609.7 611.3 611.5 611.8 11717.9 11737.6 11766.8 12032.2 12085.5 11756.2 11953.1 12084.0 12278.8 13273.1 12177.9 12193.5 12277.6 12467.6 12738.8 3771.2 3788.3 3852.6 3945.6 3965.8 69503.7 70444.6 70710.6 71789.3 71824.6 9728.7 9980.6 10069.6 10114.5 10360.0 18818.9 19106.5 19139.2 19165.0 19182.6 7065.9 7132.8 7289.0 7401.0 7547.5 3656.7 3744.0 3818.2 3823.3 3952.4 2809.8 2922.1 2923.3 2933.4 2953.8 522.3 524.6 528.1 532.2 539.5 551.3 563.9 582.4 587.8 591.1 7949.7 8102.7 8183.3 8222.3 8264.7 516.4 580.4 588.4 620.8 630.3 3738.3 3745.0 3828.4 3883.3 4013.3 105706.0 106176.0 106368.0 111910.0 112852.0 90414.0 90801.0 92264.1 94189.5 97351.0 50353.7 82074.6 85263.6 85557.0 86121.1 7238.7 7261.2 7531.4 7689.8 7856.1 44620.0 45209.5 45587.0 47527.5 48668.5 46019.0 46210.0 63178.4 64188.7 67427.6 11819.8 12145.3 12508.1 12508.3 13105.9 12214.0 12546.4 12790.1 13075.7 13559.5 10348.3 10758.2 11062.0 11115.1 11149.4 10559.7 10651.3 10816.3 10834.0 11034.4 10716.9 11145.1 11554.4 11579.9 11657.6 8775.4 9359.8 9363.0 9428.2 9720.8 9201.9 9661.2 9766.8 9771.4 9804.1 14:31:35 INFO - PROCESS | 4300 | -------- Summary: end -------- 14:31:35 INFO - PROCESS | 4300 | 14:31:36 INFO - TEST-INFO | 4300: exit 0 14:31:36 INFO - TEST-OK | dromaeo_css | took 1120090ms 14:31:36 INFO - TEST-START | kraken 14:31:36 INFO - operating with platform_type : linux_ 14:31:36 INFO - Initialising browser for kraken test... 14:31:36 INFO - TEST-INFO | started process 4491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8eM_Ui/profile http://localhost:49938/getInfo.html) 14:31:41 INFO - PROCESS | 4491 | __metrics Screen width/height:1600/1200 14:31:41 INFO - PROCESS | 4491 | colorDepth:24 14:31:41 INFO - PROCESS | 4491 | Browser inner width/height: 1024/697 14:31:41 INFO - PROCESS | 4491 | __metrics 14:31:41 INFO - PROCESS | 4491 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:31:41 INFO - PROCESS | 4491 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:31:42 INFO - TEST-INFO | 4491: exit 0 14:31:42 INFO - Browser initialized. 14:31:42 INFO - Running cycle 1/1 for kraken test... 14:31:42 INFO - TEST-INFO | started process 4570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8eM_Ui/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:31:54 INFO - PROCESS | 4570 | RSS: Main: 157831168 14:31:54 INFO - PROCESS | 4570 | 14:31:57 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49938/tests/kraken/driver.html?testName=audio-beat-detection) 14:31:58 INFO - PROCESS | 4570 | RSS: Main: 214073344 14:31:58 INFO - PROCESS | 4570 | 14:32:01 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49938/tests/kraken/driver.html?testName=audio-dft) 14:32:01 INFO - PROCESS | 4570 | RSS: Main: 191492096 14:32:01 INFO - PROCESS | 4570 | 14:32:05 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49938/tests/kraken/driver.html?testName=audio-fft) 14:32:05 INFO - PROCESS | 4570 | RSS: Main: 272052224 14:32:05 INFO - PROCESS | 4570 | 14:32:07 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49938/tests/kraken/driver.html?testName=audio-oscillator) 14:32:08 INFO - PROCESS | 4570 | RSS: Main: 180662272 14:32:08 INFO - PROCESS | 4570 | 14:32:10 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49938/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:32:11 INFO - PROCESS | 4570 | RSS: Main: 184737792 14:32:11 INFO - PROCESS | 4570 | 14:32:14 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49938/tests/kraken/driver.html?testName=imaging-darkroom) 14:32:15 INFO - PROCESS | 4570 | RSS: Main: 221614080 14:32:15 INFO - PROCESS | 4570 | 14:32:18 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49938/tests/kraken/driver.html?testName=imaging-desaturate) 14:32:19 INFO - PROCESS | 4570 | RSS: Main: 220807168 14:32:19 INFO - PROCESS | 4570 | 14:32:22 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49938/tests/kraken/driver.html?testName=json-parse-financial) 14:32:22 INFO - PROCESS | 4570 | RSS: Main: 220606464 14:32:22 INFO - PROCESS | 4570 | 14:32:24 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49938/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:32:25 INFO - PROCESS | 4570 | RSS: Main: 185143296 14:32:25 INFO - PROCESS | 4570 | 14:32:27 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:32:27 INFO - PROCESS | 4570 | RSS: Main: 191680512 14:32:27 INFO - PROCESS | 4570 | 14:32:29 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:32:30 INFO - PROCESS | 4570 | RSS: Main: 195108864 14:32:30 INFO - PROCESS | 4570 | 14:32:33 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:32:33 INFO - PROCESS | 4570 | RSS: Main: 190087168 14:32:33 INFO - PROCESS | 4570 | 14:32:36 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:32:37 INFO - PROCESS | 4570 | RSS: Main: 193085440 14:32:37 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:49938/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49938/tests/kraken/driver.html?testName=ai-astar) 14:32:39 INFO - PROCESS | 4570 | RSS: Main: 243838976 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | __start_tp_report 14:32:39 INFO - PROCESS | 4570 | _x_x_mozilla_page_load 14:32:39 INFO - PROCESS | 4570 | _x_x_mozilla_page_load_details 14:32:39 INFO - PROCESS | 4570 | |i|pagename|runs| 14:32:39 INFO - PROCESS | 4570 | |0;ai-astar;104;116;114;115;115;117;117;115;116;114 14:32:39 INFO - PROCESS | 4570 | |1;audio-beat-detection;143;123;124;172;129;127;156;129;155;128 14:32:39 INFO - PROCESS | 4570 | |2;audio-dft;163;161;161;160;162;162;162;161;161;161 14:32:39 INFO - PROCESS | 4570 | |3;audio-fft;76;74;76;116;76;75;74;76;97;75 14:32:39 INFO - PROCESS | 4570 | |4;audio-oscillator;85;87;130;86;86;86;120;84;84;132 14:32:39 INFO - PROCESS | 4570 | |5;imaging-gaussian-blur;117;117;116;116;116;116;116;116;117;116 14:32:39 INFO - PROCESS | 4570 | |6;imaging-darkroom;147;147;146;148;146;146;146;147;146;146 14:32:39 INFO - PROCESS | 4570 | |7;imaging-desaturate;94;96;95;95;95;95;95;95;94;95 14:32:39 INFO - PROCESS | 4570 | |8;json-parse-financial;64;62;63;62;63;65;65;64;64;63 14:32:39 INFO - PROCESS | 4570 | |9;json-stringify-tinderbox;65;65;65;66;66;64;64;65;65;66 14:32:39 INFO - PROCESS | 4570 | |10;stanford-crypto-aes;81;85;78;79;78;80;79;79;81;83 14:32:39 INFO - PROCESS | 4570 | |11;stanford-crypto-ccm;140;131;129;126;127;127;139;133;169;141 14:32:39 INFO - PROCESS | 4570 | |12;stanford-crypto-pbkdf2;167;169;174;169;165;165;166;168;166;166 14:32:39 INFO - PROCESS | 4570 | |13;stanford-crypto-sha256-iterative;67;68;67;67;67;67;66;67;67;70 14:32:39 INFO - PROCESS | 4570 | __end_tp_report 14:32:39 INFO - PROCESS | 4570 | __start_cc_report 14:32:39 INFO - PROCESS | 4570 | _x_x_mozilla_cycle_collect,1941 14:32:39 INFO - PROCESS | 4570 | __end_cc_report 14:32:39 INFO - PROCESS | 4570 | __startTimestamp1470259959434__endTimestamp 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | ------- Summary: start ------- 14:32:39 INFO - PROCESS | 4570 | Number of tests: 14 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.50 stddev:3.77 (3.3%) stddev-sans-first:1.13 14:32:39 INFO - PROCESS | 4570 | Values: 104.0 116.0 114.0 115.0 115.0 117.0 117.0 115.0 116.0 114.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:136.00 stddev:16.98 (12.5%) stddev-sans-first:17.93 14:32:39 INFO - PROCESS | 4570 | Values: 143.0 123.0 124.0 172.0 129.0 127.0 156.0 129.0 155.0 128.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.67 14:32:39 INFO - PROCESS | 4570 | Values: 163.0 161.0 161.0 160.0 162.0 162.0 162.0 161.0 161.0 161.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:13.94 (18.3%) stddev-sans-first:14.64 14:32:39 INFO - PROCESS | 4570 | Values: 76.0 74.0 76.0 116.0 76.0 75.0 74.0 76.0 97.0 75.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:85.50 stddev:20.49 (24.0%) stddev-sans-first:21.18 14:32:39 INFO - PROCESS | 4570 | Values: 85.0 87.0 130.0 86.0 86.0 86.0 120.0 84.0 84.0 132.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 14:32:39 INFO - PROCESS | 4570 | Values: 117.0 117.0 116.0 116.0 116.0 116.0 116.0 116.0 117.0 116.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 14:32:39 INFO - PROCESS | 4570 | Values: 147.0 147.0 146.0 148.0 146.0 146.0 146.0 147.0 146.0 146.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 14:32:39 INFO - PROCESS | 4570 | Values: 94.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:1.08 (1.7%) stddev-sans-first:1.13 14:32:39 INFO - PROCESS | 4570 | Values: 64.0 62.0 63.0 62.0 63.0 65.0 65.0 64.0 64.0 63.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 14:32:39 INFO - PROCESS | 4570 | Values: 65.0 65.0 65.0 66.0 66.0 64.0 64.0 65.0 65.0 66.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:2.26 (2.8%) stddev-sans-first:2.39 14:32:39 INFO - PROCESS | 4570 | Values: 81.0 85.0 78.0 79.0 78.0 80.0 79.0 79.0 81.0 83.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:136.00 stddev:12.84 (9.4%) stddev-sans-first:13.54 14:32:39 INFO - PROCESS | 4570 | Values: 140.0 131.0 129.0 126.0 127.0 127.0 139.0 133.0 169.0 141.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:167.50 stddev:2.72 (1.6%) stddev-sans-first:2.88 14:32:39 INFO - PROCESS | 4570 | Values: 167.0 169.0 174.0 169.0 165.0 165.0 166.0 168.0 166.0 166.0 14:32:39 INFO - PROCESS | 4570 | 14:32:39 INFO - PROCESS | 4570 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 14:32:39 INFO - PROCESS | 4570 | Values: 67.0 68.0 67.0 67.0 67.0 67.0 66.0 67.0 67.0 70.0 14:32:39 INFO - PROCESS | 4570 | -------- Summary: end -------- 14:32:39 INFO - PROCESS | 4570 | 14:32:40 INFO - TEST-INFO | 4570: exit 0 14:32:40 INFO - TEST-OK | kraken | took 63764ms 14:32:40 INFO - SUITE-END | took 1183s 14:32:40 INFO - Completed test suite (00:19:43) 14:32:40 INFO - javascript benchmark 14:32:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.100550310143, 2627.251233398482, 2649.854258015809, 2671.4389978104596, 2672.652555167829, 4598.149342371664, 4633.212353899837, 4732.219183834667, 4798.520147985204, 4859.586935110516, 4674.392328997225, 4674.532546745324, 4707.035057813148, 4736.763161841911, 4776.283557466381, 4391.27543955247, 4430.4683437987405, 4438.689291749579, 4457.554244575549, 4540.7502587357685, 4364.803583838735, 4509.526499717532, 4526.841560545382, 4543.4547854257535, 4581.198290299201, 4217.430646862665, 4271.487421509408, 4379.189849877783, 4428.645708343357, 4449.42157519522, 4335.393044973706, 4426.911461770746, 4428.202923473774, 4455.844045458393, 4506.6169375602785, 4408.030233348658, 4411.404460397837, 4417.779111044467, 4456.153330867125, 4460.085682435093, 4536.795844186987, 4777.450593181759, 4787.952120478821, 4807.0, 4823.13183626947, 5110.514501122388, 5146.639735218536, 5168.7415629218385, 5213.895722085575, 5225.738713064369, 5213.061648903196, 5219.5041471060185, 5245.711485868275, 5283.894322113575, 5291.629585928987, 4591.816327346935, 4902.730349830758, 4909.2881532177635, 4912.410510738734, 4913.975430122862, 5248.239005344204, 5284.07528682479, 5289.127293996525, 5405.70268635221, 5407.053765591006, 5116.744162791845, 5130.9230361544605, 5133.435322114565, 5176.301199338041, 5180.559652429511, 4940.975295123573, 5193.636445448858, 5208.374995000624, 5254.369475662945, 5254.7635356409055, 5028.698278103326, 5060.139776238048, 5086.516780905882, 5115.309433226541, 5167.302414174113, 5087.6947383156385, 5091.643584949092, 5097.0, 5103.055934652101, 5142.640015198975, 4093.6929730269753, 4285.657147428198, 4295.677824163137, 4320.330348795942, 4367.825286988548, 3678.9080272992855, 3751.174741556853, 3761.6990640749223, 3808.8286027128847, 3838.1747924196434, 4473.843415480443, 4475.0, 4475.037866858641, 4488.3940668010055, 4517.728936263835, 5152.562032227228, 5194.6104042196985, 5201.947980520146, 5206.609504287194, 5249.081410753103, 4200.600942910419, 4397.07661391105, 4436.179306828182, 4452.732836029783, 4470.329450582373, 4673.112108699336, 4674.836380726658, 4676.462206846256, 4711.387519622495, 4714.198586240347, 4863.0, 4869.464358920445, 4871.123197824426, 4875.219964805685, 4926.187179115406, 4961.330220420341, 5092.0, 5125.974370128126, 5147.845564633067, 5187.455317191791, 4845.224764037737, 4856.39295088114, 4865.513448655106, 4897.216445368724, 4909.263610458317, 5059.418166910852, 5065.8733531662, 5065.898682026413, 5069.5944324454695, 5112.795488180431, 4901.975490122527, 4969.552740253394, 4992.425871024879, 5026.246063090565, 5165.837686520653, 4915.26271059344, 4915.778789954532, 4916.360873086476, 4919.0, 4930.975345123395, 4892.8532144035735, 4895.828645997373, 4896.143174944442, 4925.679830810986, 4945.950540494549, 4695.154872122914, 4833.589144922721, 4892.412910450626, 4942.20924652054, 4979.900401992012, 5092.898142037213, 5121.871953201051, 5124.359455068116, 5126.948730512647, 5132.563732082665, 4986.351774269467, 4988.600911926965, 4995.525425084611, 4998.625103117353, 5035.395752509722], "value": 4714.355795370639, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.3691591585592, 564.303085689174, 569.1349149293075, 578.3118089473529, 587.5886879184571, 30436.69563304369, 42900.9969302149, 44236.115277694465, 44237.557624423745, 44364.11271774563, 41131.0, 41131.38302925458, 41143.58856411435, 41545.584544154546, 41567.37648935268, 7637.656305466254, 7783.377329813629, 7787.493812902143, 7789.4157938154585, 7832.334251588622, 7770.222977702213, 7849.411294152933, 7856.528608283521, 7862.410319226053, 7866.292033716964, 7840.960795196044, 7882.566458844762, 7888.921110788876, 7893.368530517573, 7925.128235894047, 7872.212778722111, 7890.250426209501, 7898.881516777253, 7905.565193914333, 7909.446338756289, 7751.534907905488, 7814.296713295831, 7819.452638315263, 7827.6477558510005, 7827.882581761278, 38190.80904595487, 38365.808170959244, 38414.61585384138, 38518.614813852066, 38537.421938670945, 3145.7011583899493, 3187.824669643169, 3196.5844440222622, 3338.1988322802595, 3462.290230502741, 29418.852905735548, 29690.851545742134, 29705.10884673463, 29929.251768705737, 30945.845270773727, 3120.687931206884, 3151.952720709191, 3169.2710676544525, 3237.951430728541, 3249.4151052810485, 257668.0, 258912.9999999981, 259638.0, 261316.0, 261923.0, 31828.522572161437, 53136.73431632817, 53701.1944820828, 53761.0, 53787.462125379025, 32438.0, 33447.498287525705, 33495.83252083724, 33534.0, 33590.0, 14028.929855350862, 14167.362468688934, 14172.645683857985, 14193.14841109516, 14194.574162775134, 11533.88466115328, 11540.711482213012, 11567.074634029257, 11625.069994400428, 11654.067674586013, 7583.810404739816, 7612.8477430452185, 7673.079230492265, 7679.462437629424, 7729.381649468029, 3022.2897619059504, 3027.3793872256133, 3052.648945371266, 3066.6626671066165, 3072.6620071792086, 918.7473444802732, 936.3305236755753, 941.275218082073, 959.5729900194486, 981.4307701533237, 27744.306392340353, 27891.442171156465, 28802.0, 28875.0, 28935.0, 805.5810978291256, 934.7709811096325, 977.9804403911882, 1004.2116938203475, 1023.6519583341551, 13905.582832515041, 14586.19775912335, 14733.263336833115, 14954.0, 14954.626134346729, 369023.0, 372921.0, 373331.0, 373856.0, 374528.0, 50290.74854625703, 50692.0, 50715.7464212684, 50932.0, 50940.49059509357, 1007.7732510185297, 1057.5241141486363, 1161.1523587780798, 1182.3437991914398, 1206.6500714792612, 25793.87103064473, 28997.565036524047, 29403.705962940952, 29497.557536636537, 29806.442582631786, 47409.0, 48196.0, 48259.75870120627, 48269.5173048265, 48317.27524087071, 275711.0, 276726.0, 276756.0, 277898.0, 278200.0, 25221.873890630428, 25981.22056338313, 26119.869400653635, 26624.8668756655, 39283.0, 41549.0, 41596.0, 42049.369259461746, 42101.578984209766, 43177.35233971556, 35574.82212588921, 36051.27897441984, 36356.454653179695, 36910.26179476449, 36969.44545831868, 36125.458118127724, 36198.063745457664, 36234.637653623126, 37679.0, 37826.810865945496, 35489.29021419609, 35794.463083053255, 35939.101522461104, 36160.63839361678, 36489.81755091208, 30871.536926946563, 32408.513872291463, 32418.513722293712, 32550.674493254763, 32881.67118328786, 24886.875565622056, 25111.623325650497, 25514.362140946625, 25600.743992559837, 25720.61419078678], "value": 16585.683130604142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.808635034855, 350.48653722296837, 353.15067263231936, 355.8718861209964, 374.55053935277675, 12917.741645167103, 16369.42707005255, 16611.169441527934, 16694.08182549961, 17527.298908043664, 15028.549143525684, 15163.696726065473, 15897.602559936007, 16013.519594412186, 16074.59813504663, 4400.0, 5044.798208071682, 5159.355080614923, 5208.348956380452, 5258.737063146847, 3631.7457777955556, 3805.143842635399, 5143.434222235551, 5688.687122208277, 6100.328963813977, 6027.126066720323, 6120.540959428038, 6143.416375444348, 6161.229846269216, 6164.691765411734, 5830.562707796911, 6009.369016253296, 6025.668588227646, 6035.155078288999, 6035.698215089228, 5277.340332458442, 5278.656887302313, 5286.259923610698, 5295.576353891679, 6407.87184256317, 22177.556448871095, 23357.41606459835, 23764.04943802246, 23824.523509529885, 24341.756582434125, 2086.812186903186, 2180.008096316172, 2284.383216531544, 2446.7797898189247, 2492.2523243027017, 13288.73422531544, 14517.346719397652, 15245.16151611672, 15424.845751542565, 15709.214539273105, 1888.0748433267738, 1896.1183049881733, 1898.8196121368449, 1909.847212223019, 1930.7683078030586, 81572.0, 86449.0, 88442.0, 88637.55681221552, 88904.5554772222, 16534.83465165357, 16644.916775416044, 16652.250648720837, 16674.166291685367, 17602.207900644502, 29321.413571728444, 29467.263318416783, 29528.852355738512, 29546.261343466584, 29557.408851822842, 8546.786330341667, 8674.349423793241, 8739.033717413658, 8873.866891996626, 8923.687670931486, 7257.92742072583, 7330.6701198445135, 7791.649375778104, 8024.719134830253, 8026.277635012877, 8328.66685332592, 8443.28232100266, 8452.746417607359, 8634.136586341441, 8666.263367613697, 2731.6721993360925, 3073.3853229354218, 3114.0813460029344, 3204.6154461464307, 3306.487494438366, 661.8974059020859, 773.7949443397504, 828.2959484438179, 915.3043686798081, 969.2972594868663, 16164.233939032772, 16516.256768445448, 16983.915080424766, 17134.742978855327, 17191.742123868153, 737.3953358246187, 833.7873842170208, 866.8873046503915, 871.0070519607652, 923.2014307623991, 7256.0, 9188.26493880488, 9442.527873606292, 9525.809483810424, 9547.61809527625, 411474.0, 418620.0, 435135.0, 436022.0, 438313.0, 78487.60756196182, 79306.20693793104, 79605.0, 80091.19908800954, 82995.17004829996, 892.2683399612256, 911.3165126155384, 917.1333090229803, 917.655879045358, 920.3143657974717, 14376.137431754129, 14735.410583576537, 14958.980537627282, 14993.500649934918, 15035.699286014436, 35375.10115592524, 35451.46822797608, 35787.82106089453, 35843.64156358403, 36346.8182659085, 51499.48500514947, 51607.225891610906, 51894.22158667548, 52036.219456707426, 52315.47684523106, 7339.59632220233, 7583.620818959141, 7612.391008719178, 7737.961310193413, 7853.725119620786, 6162.845928851814, 6821.658917054028, 9089.863652045093, 10749.19381046409, 10894.291871028357, 9528.28537859677, 9582.137607615348, 9797.0, 9838.704838854846, 9899.208063355058, 9701.126898579161, 9874.901250987685, 9881.061299176567, 9954.452505111998, 10088.142507886912, 9318.0, 9395.577199025924, 9580.185684216642, 9915.702528924145, 10436.947815260875, 7335.156457007299, 7335.193128755725, 7425.368843648351, 7574.886376704464, 7674.961625191839, 7341.706331746884, 9092.318076144447, 9692.127708506268, 9818.165455936325, 10036.598536058767], "value": 8969.62059747726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.3513513513512, 1387.687770251693, 1429.3639330497926, 1625.7154997875384, 1659.502149355193, 6671.933280667192, 7206.927930720692, 7216.025836512063, 7222.133343998715, 7227.20500744919, 6099.57302988791, 6295.527835412351, 6720.9663951680295, 6948.235694073661, 7095.077639906818, 2099.7900209979034, 2123.044629916536, 2124.5326028273753, 2125.1818050050765, 2127.723395958531, 2052.6407878621258, 2088.749350077993, 2139.860909040915, 2143.9249626263086, 2147.4309308033385, 1799.991000044998, 2058.5368292134226, 2131.9253826116087, 2138.1874887542713, 2153.3862849088014, 2103.463616777712, 2116.523782149012, 2127.4681329667583, 2131.626965281085, 2134.1676746069047, 2115.375964090597, 2117.163720330461, 2120.067170444999, 2122.1617461102933, 2124.0866427436194, 7244.058272424604, 7244.985702001724, 7284.74503392384, 7293.124825021031, 7305.963470182615, 4048.8987775305854, 4103.815328310204, 4153.376993450976, 4153.501579810412, 4158.313878210122, 2726.1412655013805, 2849.957250641242, 2880.8559572021527, 2885.0335137728885, 2885.6970018148004, 929.7907970706536, 977.6773664690635, 987.7086259553378, 994.462989985407, 1002.8094662014194, 9491.288153388523, 9546.474943878084, 9546.474943878084, 9556.952215238949, 9558.235341172758, 3743.1765011697385, 3855.9421608675893, 3950.9604903951167, 4016.136530645925, 4018.578049304839, 8248.8350232995, 8249.463784854086, 8251.95874020638, 8261.256486916087, 8264.710735124241, 4260.2970509866145, 4262.232798096373, 4263.893402664959, 4263.978680106579, 4264.317709166518, 3859.459675645412, 3868.9613103869165, 3872.244912242142, 3875.6318149775725, 3884.786336751449, 3245.7727959043114, 3344.7324214062814, 3375.8312084395684, 3419.26485805553, 3423.1613254752747, 1009.7526106103904, 1012.6860673191334, 1016.7305663998899, 1028.7068185567077, 1037.906588407047, 651.6741629185407, 730.1292071469567, 843.6878355008686, 861.5175501719058, 869.860822268434, 4872.244802055687, 4991.625628077836, 4998.400191976986, 5143.048536020773, 5213.921791173136, 821.1747194069922, 845.9365547583833, 849.1890244816186, 883.3595643158787, 909.8498747706686, 3229.838508074587, 3399.6940275375337, 3417.9316413671586, 3420.589529256505, 3427.6572342765526, 8189.303909167668, 8696.565171741388, 8697.521636310064, 8711.21599056088, 8719.215270625675, 8493.915060849313, 8640.222379985706, 8662.6101825418, 8668.653253869774, 8679.088695686989, 6684.0, 6848.246692863781, 6864.897026544606, 6867.519273650796, 6885.070515480446, 4764.33299338086, 4800.711957282574, 4854.320395144612, 4919.63102767301, 4950.183219768698, 8063.8790418144945, 8101.635426405708, 8144.900438440734, 8185.672573097009, 8232.135625759209, 8295.170482951657, 8299.543525106177, 8377.790555236168, 8377.874331884905, 8407.285380742705, 5891.086881533369, 6052.727627256872, 6055.212822333068, 6055.333913269449, 6058.0, 6805.625690587065, 6816.079829222991, 6875.4155896749335, 6876.931230687629, 6891.793246202622, 4722.811087556459, 6156.045812899008, 6168.691565421801, 6170.290416601969, 6184.196054512884, 6154.169187159855, 6195.256569211543, 6227.844303892439, 6374.776882809265, 6416.165898433173, 5670.234518340137, 5775.960327141155, 5775.971120144372, 5776.768929242783, 5781.913271300849, 4130.876073717794, 4144.79276036203, 4145.81343839534, 4252.851150209728, 4451.399061126706, 4330.285502891988, 4354.804033818423, 4366.781660917005, 4490.326451032372, 4508.18852606534], "value": 3863.8947644972814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.0936912778439, 294.16017270692174, 300.95034319337304, 301.3490859742956, 303.0650443382166, 4772.379590653214, 4817.373741413611, 4829.830955916544, 4908.938245936706, 4909.116359055369, 4503.662225333097, 4627.699199552027, 4704.647151463637, 4769.165396055694, 4770.260609605517, 3792.924141517168, 3799.3920972644382, 3805.486259354979, 3809.4857194278834, 3813.275477659236, 3808.7524310919703, 3814.9237015259678, 3820.4460353248764, 3826.6747326477143, 3827.5215598050245, 3581.3374525712698, 3651.963480365202, 3756.661900428961, 3762.887113386589, 3784.489093972306, 3756.1736417988, 3756.4740936268945, 3758.4174452959696, 3762.5108735864437, 3766.60450652683, 3311.420501412267, 3331.1006028372444, 3332.100332910125, 3340.8997730067895, 3346.297277571713, 4437.733735975852, 4625.560571745679, 4629.305604159403, 4632.698874573142, 4636.35091087248, 4212.894677633084, 4216.47294088239, 4227.87316380509, 4233.23801715691, 4236.0, 4991.750412479361, 4992.425871024806, 4996.500349964975, 5006.774695138727, 5018.47306032865, 3529.4176460883923, 3546.202104526487, 3550.431930891071, 3554.431290993429, 3556.3065202285566, 4913.656044076916, 5137.640365174402, 5171.017506673757, 5186.40356359016, 5199.66202196856, 3579.409397449444, 3601.657842504958, 3601.8019008954757, 3604.7563590561213, 3620.244181571988, 3671.2473942841652, 3672.247189326182, 3672.8714494992546, 3675.963240367562, 3676.7499050322485, 3982.3628219484744, 4194.203101410722, 4202.558731333227, 4203.285441474956, 4205.201011807747, 4972.602191824645, 5003.799848006039, 5006.148954677714, 5009.373828271466, 5031.0, 1262.4697626996683, 1271.1419791640606, 1275.6491964709778, 1276.049343239274, 1279.6883194725294, 813.2883726739103, 846.6401779243797, 865.9437136586101, 874.2874557235959, 875.9474431534128, 4427.335899615019, 4427.690061695715, 4433.733975961452, 4434.467863856358, 4442.244818380883, 5111.7699703513435, 5113.181890897438, 5115.283860259567, 5119.513646203604, 5120.564751996122, 4315.8489452869, 4342.177584570055, 4374.27824408969, 4425.491068527096, 4430.911381772346, 2686.3210493441597, 2699.689535703419, 2699.91900242993, 2704.7700945419465, 2708.3229192701824, 4790.209615413488, 4792.856214313576, 4806.49531799156, 4847.321375007502, 4855.36880205571, 4904.033228216258, 5151.742412879416, 5208.869778255498, 5215.765290561858, 5243.580513558906, 4790.634669119424, 4794.712317261045, 4794.904101917942, 4801.0, 4814.975925120352, 4991.9500804991485, 5071.833478299938, 5084.41529224144, 5093.567046801063, 5102.158143906362, 4829.637777166657, 4981.452040275639, 4990.38005047353, 5071.7464126792775, 5095.694258344512, 4866.343043689057, 4870.975645121752, 4904.6566740327835, 4907.68100073494, 4912.606991440604, 3606.9819650901577, 3624.800635965047, 3625.7643253189513, 3633.5276414066, 3641.198936234024, 5026.773795179152, 5139.0, 5155.407128180307, 5182.507661772125, 5185.559227465707, 4987.458904177929, 5038.294638750651, 5055.898882022412, 5068.9239661405845, 5072.188449848007, 5052.418971818288, 5062.190049592047, 5063.215201643751, 5064.721440320818, 5083.87290317745, 4787.928181077217, 4949.82675606352, 4991.825286114969, 5001.599872010303, 5005.274235235797, 4858.78135483897, 5000.749962501933, 5065.468125846733, 5073.568746656428, 5115.053715062725, 4969.279454479152, 4969.503049695001, 4970.403551573834, 4973.825916092919, 4975.154223782039], "value": 3770.1505740888283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.4380057947847, 637.0889627832204, 642.9678516074198, 680.2109552918612, 739.4601940583374, 11863.0, 12892.548760793374, 12900.806487902677, 12918.354082295871, 12936.159149655266, 12722.809157862639, 12835.101542891978, 12865.5497057603, 12916.095873288874, 12923.159994600344, 628.7799270255397, 640.4268179978916, 753.8793792993122, 771.1555846348253, 776.130733578393, 785.8271180340345, 786.5831109511968, 790.4506368074191, 790.8260182759784, 791.1416113516841, 838.9370797190205, 839.8698201778703, 840.4117118017382, 841.5161282262693, 843.2031730015138, 662.8309781005861, 802.5265093594749, 825.7976795685036, 826.8263664630465, 827.1067247372823, 608.2822269721727, 609.6768712582294, 611.2939554814196, 611.5474548833876, 611.8011646214998, 11717.882821171765, 11737.647870563897, 11766.823497647456, 12032.217905836093, 12085.45615447307, 11756.23584467007, 11953.103517236243, 12084.0, 12278.754424911542, 13273.13724607907, 12177.878221217763, 12193.512259509609, 12277.631671049883, 12467.563635272812, 12738.80891786624, 3771.208046310278, 3788.2612890486366, 3852.5569559500736, 3945.6251656092627, 3965.801709914522, 69503.65248173778, 70444.6477767613, 70710.64644676795, 71789.28210717878, 71824.64087679528, 9728.708138755848, 9980.550875210633, 10069.597216111273, 10114.494275286206, 10360.0, 18818.90590547066, 19106.52233694141, 19139.2344306229, 19165.0, 19182.616347672967, 7065.89401158983, 7132.786016419515, 7289.0, 7400.9629951850975, 7547.471676982558, 3656.689181419556, 3743.981280093582, 3818.236352729465, 3823.330917089498, 3952.446657467957, 2809.789265805073, 2922.1165279429333, 2923.327634644044, 2933.3693255950075, 2953.822770633769, 522.2949018824633, 524.5750941737128, 528.118042868403, 532.1804421191322, 539.4578448659073, 551.283331668829, 563.9069553523705, 582.4263100845664, 587.7825204674213, 591.104476717773, 7949.7217597383815, 8102.675892964333, 8183.263506284463, 8222.342212623096, 8264.669413223404, 516.3597139546935, 580.3760956971273, 588.3586890289605, 620.807549659598, 630.3318482408662, 3738.345789486829, 3745.0, 3828.406596977473, 3883.3010058189234, 4013.2976729072298, 105706.0, 106176.0, 106368.0, 111910.0, 112852.0, 90414.0, 90801.0, 92264.0773592269, 94189.5290523543, 97351.0, 50353.74823125861, 82074.58962705148, 85263.57368213119, 85556.99999999875, 86121.13878861383, 7238.674259658434, 7261.201267860425, 7531.3974882008215, 7689.807754806175, 7856.096548896721, 44619.98420142073, 45209.547904520536, 45587.0, 47527.52472475231, 48668.513314866395, 46019.0, 46210.0, 63178.36821631725, 64188.679056604415, 67427.56014157923, 11819.763604727685, 12145.271283723005, 12508.124431289722, 12508.312042837731, 13105.868941310466, 12214.0, 12546.435410406371, 12790.104692671795, 13075.738485230433, 13559.525416610371, 10348.327358721659, 10758.246922715334, 11062.0, 11115.055220306365, 11149.386783726974, 10559.72965812354, 10651.30766500206, 10816.296940699143, 10834.0, 11034.393108379116, 10716.892831071902, 11145.108391328513, 11554.364509951698, 11579.88420115788, 11657.591984280509, 8775.36152333801, 9359.80591176902, 9363.0, 9428.198603118812, 9720.805583888425, 9201.861972070557, 9661.178799802095, 9766.75583110428, 9771.364861283993, 9804.117629413387], "value": 6600.544834934649, "unit": "score"}], "name": "dromaeo_css", "value": 6380.051818747535, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 114.0, 115.0, 115.0, 117.0, 117.0, 115.0, 116.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 123.0, 124.0, 172.0, 129.0, 127.0, 156.0, 129.0, 155.0, 128.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 160.0, 162.0, 162.0, 162.0, 161.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 116.0, 76.0, 75.0, 74.0, 76.0, 97.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 130.0, 86.0, 86.0, 86.0, 120.0, 84.0, 84.0, 132.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 148.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 62.0, 63.0, 65.0, 65.0, 64.0, 64.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 64.0, 64.0, 65.0, 65.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 78.0, 79.0, 78.0, 80.0, 79.0, 79.0, 81.0, 83.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 131.0, 129.0, 126.0, 127.0, 127.0, 139.0, 133.0, 169.0, 141.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 174.0, 169.0, 165.0, 165.0, 166.0, 168.0, 166.0, 166.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 70.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1531.3999999999999, "alertThreshold": 2.0}]} 14:32:40 INFO - Return code: 0 14:32:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:32:40 INFO - # TBPL SUCCESS # 14:32:40 INFO - Running post-action listener: _resource_record_post_action 14:32:40 INFO - [mozharness: 2016-08-03 21:32:40.720732Z] Finished run-tests step (success) 14:32:40 INFO - Running post-run listener: _resource_record_post_run 14:32:40 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 40960; Write bytes: 296976384; Read time: 52; Write time: 213944 14:32:40 INFO - TinderboxPrint: CPU usage
12.9% 14:32:40 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 52 14:32:40 INFO - TinderboxPrint: I/O write bytes / time
296,976,384 / 213,944 14:32:40 INFO - TinderboxPrint: CPU idle
8,336.2 (87.0%) 14:32:40 INFO - TinderboxPrint: CPU user
1,197.3 (12.5%) 14:32:40 INFO - TinderboxPrint: Swap in / out
0 / 0 14:32:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13778944; Read time: 0; Write time: 2928 14:32:40 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 36864; Write bytes: 283197440; Read time: 36; Write time: 211016 14:32:41 INFO - Running post-run listener: _upload_blobber_files 14:32:41 INFO - Blob upload gear active. 14:32:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:32:41 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 14:32:41 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:32:41 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:32:41 INFO - (blobuploader) - INFO - Open directory for files ... 14:32:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:32:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:32:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:42 INFO - (blobuploader) - INFO - Done attempting. 14:32:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:32:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:32:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:46 INFO - (blobuploader) - INFO - Done attempting. 14:32:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:32:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:32:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:47 INFO - (blobuploader) - INFO - Done attempting. 14:32:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:32:47 INFO - Return code: 0 14:32:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:32:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:32:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43403e3c04b413ec1cd2a3d09b27479d7928305ca5c64c9888f4c655c10bafd421fbc5dfb8c0465a25a1312dcc2680f8589f01ec64153c69e58864619b9a0e32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3383898fea22af17808c5c6fe6ca638bcdf943cde6794e9849b2473d392937fafeeee1ee8b444f9e74176d2ad9e70c60c562d59c191380ae760565a3325213d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ff2bd00edfb252fc65b244e1c059af49e18b23d5d85f9a8207120303b36acfdf93dd2905c2d36d1b2aa750dd145232542c5ba08d1887d1449593bd18dacdda23"} 14:32:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:32:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:32:47 INFO - Contents: 14:32:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43403e3c04b413ec1cd2a3d09b27479d7928305ca5c64c9888f4c655c10bafd421fbc5dfb8c0465a25a1312dcc2680f8589f01ec64153c69e58864619b9a0e32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3383898fea22af17808c5c6fe6ca638bcdf943cde6794e9849b2473d392937fafeeee1ee8b444f9e74176d2ad9e70c60c562d59c191380ae760565a3325213d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ff2bd00edfb252fc65b244e1c059af49e18b23d5d85f9a8207120303b36acfdf93dd2905c2d36d1b2aa750dd145232542c5ba08d1887d1449593bd18dacdda23"} 14:32:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:32:47 INFO - Copying logs to upload dir... 14:32:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:32:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.880777 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-03 14:32:47.340547) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 14:32:47.345974) ========= 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=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43403e3c04b413ec1cd2a3d09b27479d7928305ca5c64c9888f4c655c10bafd421fbc5dfb8c0465a25a1312dcc2680f8589f01ec64153c69e58864619b9a0e32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3383898fea22af17808c5c6fe6ca638bcdf943cde6794e9849b2473d392937fafeeee1ee8b444f9e74176d2ad9e70c60c562d59c191380ae760565a3325213d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ff2bd00edfb252fc65b244e1c059af49e18b23d5d85f9a8207120303b36acfdf93dd2905c2d36d1b2aa750dd145232542c5ba08d1887d1449593bd18dacdda23"} build_url:https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/Cmgv7cT1T8mRpN3yhmLWvw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43403e3c04b413ec1cd2a3d09b27479d7928305ca5c64c9888f4c655c10bafd421fbc5dfb8c0465a25a1312dcc2680f8589f01ec64153c69e58864619b9a0e32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3383898fea22af17808c5c6fe6ca638bcdf943cde6794e9849b2473d392937fafeeee1ee8b444f9e74176d2ad9e70c60c562d59c191380ae760565a3325213d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ff2bd00edfb252fc65b244e1c059af49e18b23d5d85f9a8207120303b36acfdf93dd2905c2d36d1b2aa750dd145232542c5ba08d1887d1449593bd18dacdda23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 14:32:47.372826) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:32:47.373240) ========= 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=00204705a9daa57ed339a427000001b9-1470258726.696172-1771431191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 14:32:47.424113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 14:32:47.424510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 14:32:47.424892) ========= ========= Total master_lag: 0.15 =========