builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1469747279.54 results: success (0) buildid: 20160728145235 builduid: 6135823d631c4694b13d253f6e95f954 revision: fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.538399) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.539041) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.539431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.556268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.557194) ========= 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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-28 16:07:59-- 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% 46.5M=0s 2016-07-28 16:07:59 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084277 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.656216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.656593) ========= 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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020861 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.706584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:07:59.706941) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce --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 fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce --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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-28 16:07:59,968 truncating revision to first 12 chars 2016-07-28 16:07:59,969 Setting DEBUG logging. 2016-07-28 16:07:59,969 attempt 1/10 2016-07-28 16:07:59,969 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fc0cdec77407?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-28 16:08:00,280 unpacking tar archive at: autoland-fc0cdec77407/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:08:00.379834) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 16:08:00.380261) ========= script_repo_revision: fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 16:08:00.380857) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 16:08:00.381214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 16:08:00.391705) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-28 16:08:00.392213) ========= /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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:00 INFO - MultiFileLogger online at 20160728 16:08:00 in /builds/slave/test 16:08:00 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 16:08:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:00 INFO - {'append_to_log': False, 16:08:00 INFO - 'base_work_dir': '/builds/slave/test', 16:08:00 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 16:08:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:00 INFO - 'branch': 'Autoland-Non-PGO', 16:08:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:00 INFO - 'config_files': ('talos/linux_config.py',), 16:08:00 INFO - 'default_actions': ('clobber', 16:08:00 INFO - 'read-buildbot-config', 16:08:00 INFO - 'download-and-extract', 16:08:00 INFO - 'populate-webroot', 16:08:00 INFO - 'create-virtualenv', 16:08:00 INFO - 'install', 16:08:00 INFO - 'run-tests'), 16:08:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:00 INFO - 'download_minidump_stackwalk': True, 16:08:00 INFO - 'download_symbols': 'ondemand', 16:08:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:00 INFO - 'installer_path': 'installer.exe', 16:08:00 INFO - 'log_level': 'info', 16:08:00 INFO - 'log_name': 'talos', 16:08:00 INFO - 'log_to_console': True, 16:08:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:00 INFO - 'opt_config_files': (), 16:08:00 INFO - 'pip_index': False, 16:08:00 INFO - 'sps_profile': False, 16:08:00 INFO - 'sps_profile_interval': 0, 16:08:00 INFO - 'suite': 'dromaeojs', 16:08:00 INFO - 'system_bits': '32', 16:08:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:00 INFO - 'title': 'talos-linux64-ix-032', 16:08:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:00 INFO - 'use_talos_json': True, 16:08:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:00 INFO - 'work_dir': 'build'} 16:08:00 INFO - [mozharness: 2016-07-28 23:08:00.739808Z] Running clobber step. 16:08:00 INFO - Running pre-action listener: _resource_record_pre_action 16:08:00 INFO - Running main action method: clobber 16:08:00 INFO - rmtree: /builds/slave/test/build 16:08:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:01 INFO - Running post-action listener: _resource_record_post_action 16:08:01 INFO - [mozharness: 2016-07-28 23:08:01.222492Z] Finished clobber step (success) 16:08:01 INFO - [mozharness: 2016-07-28 23:08:01.222595Z] Running read-buildbot-config step. 16:08:01 INFO - Running pre-action listener: _resource_record_pre_action 16:08:01 INFO - Running main action method: read_buildbot_config 16:08:01 INFO - Using buildbot properties: 16:08:01 INFO - { 16:08:01 INFO - "project": "", 16:08:01 INFO - "product": "firefox", 16:08:01 INFO - "script_repo_revision": "production", 16:08:01 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:08:01 INFO - "repository": "", 16:08:01 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:08:01 INFO - "buildid": "20160728145235", 16:08:01 INFO - "pgo_build": "False", 16:08:01 INFO - "basedir": "/builds/slave/test", 16:08:01 INFO - "buildnumber": 153, 16:08:01 INFO - "slavename": "talos-linux64-ix-032", 16:08:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:08:01 INFO - "platform": "ubuntu64_hw", 16:08:01 INFO - "scheduler": "tests-autoland-linux64-talos", 16:08:01 INFO - "branch": "autoland", 16:08:01 INFO - "repo_path": "integration/autoland", 16:08:01 INFO - "revision": "fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce", 16:08:01 INFO - "stage_platform": "linux64", 16:08:01 INFO - "builduid": "6135823d631c4694b13d253f6e95f954", 16:08:01 INFO - "slavebuilddir": "test" 16:08:01 INFO - } 16:08:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:08:01 INFO - Running post-action listener: _resource_record_post_action 16:08:01 INFO - [mozharness: 2016-07-28 23:08:01.225033Z] Finished read-buildbot-config step (success) 16:08:01 INFO - [mozharness: 2016-07-28 23:08:01.225123Z] Running download-and-extract step. 16:08:01 INFO - Running pre-action listener: _resource_record_pre_action 16:08:01 INFO - Running main action method: download_and_extract 16:08:01 INFO - mkdir: /builds/slave/test/build/tests 16:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:01 INFO - https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:08:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:08:01 INFO - Downloaded 1361 bytes. 16:08:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:01 INFO - Using the following test package requirements: 16:08:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:08:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'jsshell-linux-x86_64.zip'], 16:08:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:08:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:08:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:01 INFO - https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:08:03 INFO - Downloaded 22749930 bytes. 16:08:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:08:05 INFO - Return code: 0 16:08:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:05 INFO - https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:08:06 INFO - Downloaded 22749930 bytes. 16:08:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:08:07 INFO - Return code: 0 16:08:07 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')]} 16:08:07 INFO - https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:08:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:08:08 INFO - Downloaded 14413725 bytes. 16:08:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:08:08 INFO - Return code: 0 16:08:08 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')]} 16:08:08 INFO - https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:08:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:08:10 INFO - Downloaded 57163755 bytes. 16:08:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:10 INFO - mkdir: /builds/slave/test/properties 16:08:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:10 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:10 INFO - Contents: 16:08:10 INFO - build_url:https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:11 INFO - Running post-action listener: _resource_record_post_action 16:08:11 INFO - Running post-action listener: set_extra_try_arguments 16:08:11 INFO - [mozharness: 2016-07-28 23:08:11.554329Z] Finished download-and-extract step (success) 16:08:11 INFO - [mozharness: 2016-07-28 23:08:11.554561Z] Running populate-webroot step. 16:08:11 INFO - Running pre-action listener: _resource_record_pre_action 16:08:11 INFO - Running main action method: populate_webroot 16:08:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:08:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:08:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:08:11 INFO - '--tppagecycles', 16:08:11 INFO - '7'], 16:08:11 INFO - 'tests': ['tsvgm']}}, 16:08:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['damp', 'tps']}, 16:08:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'tests': ['damp', 'tps']}, 16:08:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['dromaeo_dom']}, 16:08:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['basic_compositor_video']}, 16:08:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:08:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['a11yr', 16:08:11 INFO - 'ts_paint', 16:08:11 INFO - 'tpaint', 16:08:11 INFO - 'sessionrestore', 16:08:11 INFO - 'sessionrestore_no_auto_restore', 16:08:11 INFO - 'tabpaint']}, 16:08:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['tsvgx', 16:08:11 INFO - 'tsvgr_opacity', 16:08:11 INFO - 'tart', 16:08:11 INFO - 'tscrollx', 16:08:11 INFO - 'cart']}, 16:08:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:11 INFO - 'tsvgr_opacity', 16:08:11 INFO - 'tart', 16:08:11 INFO - 'tscrollx', 16:08:11 INFO - 'cart']}, 16:08:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'talos_options': ['--disable-e10s'], 16:08:11 INFO - 'tests': ['tp5o']}, 16:08:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'tests': ['tp5o']}, 16:08:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'talos_options': ['--disable-e10s', 16:08:11 INFO - '--xperf_path', 16:08:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:11 INFO - 'tests': ['tp5n']}, 16:08:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:11 INFO - 'talos_options': ['--xperf_path', 16:08:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:11 INFO - 'tests': ['tp5n']}}, 16:08:11 INFO - 'talos.zip': {'path': '', 16:08:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:08:11 INFO - Running post-action listener: _resource_record_post_action 16:08:11 INFO - [mozharness: 2016-07-28 23:08:11.569735Z] Finished populate-webroot step (success) 16:08:11 INFO - [mozharness: 2016-07-28 23:08:11.569824Z] Running create-virtualenv step. 16:08:11 INFO - Running pre-action listener: _resource_record_pre_action 16:08:11 INFO - Running main action method: create_virtualenv 16:08:11 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:11 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 16:08:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:08:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:11 INFO - Using real prefix '/usr' 16:08:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:12 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:14 INFO - Installing pip.................done. 16:08:14 INFO - Return code: 0 16:08:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:14 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 16:08:14 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 16:08:14 INFO - Using env: {'DISPLAY': ':0', 16:08:14 INFO - 'HOME': '/home/cltbld', 16:08:14 INFO - 'LANG': 'en_US.UTF-8', 16:08:14 INFO - 'LANGUAGE': 'en_US:en', 16:08:14 INFO - 'LOGNAME': 'cltbld', 16:08:14 INFO - 'MAIL': '/var/mail/cltbld', 16:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:14 INFO - 'MOZ_NO_REMOTE': '1', 16:08:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:14 INFO - 'NO_EM_RESTART': '1', 16:08:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:14 INFO - 'PWD': '/builds/slave/test', 16:08:14 INFO - 'SHELL': '/bin/bash', 16:08:14 INFO - 'SHLVL': '1', 16:08:14 INFO - 'TERM': 'linux', 16:08:14 INFO - 'TMOUT': '86400', 16:08:14 INFO - 'USER': 'cltbld', 16:08:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:14 INFO - '_': '/tools/buildbot/bin/python'} 16:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:14 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 16:08:14 INFO - Cleaning up... 16:08:14 INFO - Return code: 0 16:08:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:14 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 16:08:14 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 16:08:14 INFO - Using env: {'DISPLAY': ':0', 16:08:14 INFO - 'HOME': '/home/cltbld', 16:08:14 INFO - 'LANG': 'en_US.UTF-8', 16:08:14 INFO - 'LANGUAGE': 'en_US:en', 16:08:14 INFO - 'LOGNAME': 'cltbld', 16:08:14 INFO - 'MAIL': '/var/mail/cltbld', 16:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:14 INFO - 'MOZ_NO_REMOTE': '1', 16:08:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:14 INFO - 'NO_EM_RESTART': '1', 16:08:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:14 INFO - 'PWD': '/builds/slave/test', 16:08:14 INFO - 'SHELL': '/bin/bash', 16:08:14 INFO - 'SHLVL': '1', 16:08:14 INFO - 'TERM': 'linux', 16:08:14 INFO - 'TMOUT': '86400', 16:08:14 INFO - 'USER': 'cltbld', 16:08:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:14 INFO - '_': '/tools/buildbot/bin/python'} 16:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:14 INFO - Downloading/unpacking psutil>=3.1.1 16:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:14 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 16:08:14 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 16:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:16 INFO - Installing collected packages: psutil 16:08:16 INFO - Running setup.py install for psutil 16:08:16 INFO - building 'psutil._psutil_linux' extension 16:08:16 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 16:08:16 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 16:08:16 INFO - building 'psutil._psutil_posix' extension 16:08:16 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 16:08:16 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 16:08:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:17 INFO - Successfully installed psutil 16:08:17 INFO - Cleaning up... 16:08:17 INFO - Return code: 0 16:08:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:17 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')]} 16:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:17 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')]} 16:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:17 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 16:08:17 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 16:08:17 INFO - Using env: {'DISPLAY': ':0', 16:08:17 INFO - 'HOME': '/home/cltbld', 16:08:17 INFO - 'LANG': 'en_US.UTF-8', 16:08:17 INFO - 'LANGUAGE': 'en_US:en', 16:08:17 INFO - 'LOGNAME': 'cltbld', 16:08:17 INFO - 'MAIL': '/var/mail/cltbld', 16:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:17 INFO - 'MOZ_NO_REMOTE': '1', 16:08:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:17 INFO - 'NO_EM_RESTART': '1', 16:08:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:17 INFO - 'PWD': '/builds/slave/test', 16:08:17 INFO - 'SHELL': '/bin/bash', 16:08:17 INFO - 'SHLVL': '1', 16:08:17 INFO - 'TERM': 'linux', 16:08:17 INFO - 'TMOUT': '86400', 16:08:17 INFO - 'USER': 'cltbld', 16:08:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:17 INFO - '_': '/tools/buildbot/bin/python'} 16:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:19 INFO - Installing collected packages: mozsystemmonitor 16:08:19 INFO - Running setup.py install for mozsystemmonitor 16:08:19 INFO - Successfully installed mozsystemmonitor 16:08:19 INFO - Cleaning up... 16:08:19 INFO - Return code: 0 16:08:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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', '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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:08: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 jsonschema==2.5.1 16:08:19 INFO - Using env: {'DISPLAY': ':0', 16:08:19 INFO - 'HOME': '/home/cltbld', 16:08:19 INFO - 'LANG': 'en_US.UTF-8', 16:08:19 INFO - 'LANGUAGE': 'en_US:en', 16:08:19 INFO - 'LOGNAME': 'cltbld', 16:08:19 INFO - 'MAIL': '/var/mail/cltbld', 16:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:19 INFO - 'MOZ_NO_REMOTE': '1', 16:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:19 INFO - 'NO_EM_RESTART': '1', 16:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:19 INFO - 'PWD': '/builds/slave/test', 16:08:19 INFO - 'SHELL': '/bin/bash', 16:08:19 INFO - 'SHLVL': '1', 16:08:19 INFO - 'TERM': 'linux', 16:08:19 INFO - 'TMOUT': '86400', 16:08:19 INFO - 'USER': 'cltbld', 16:08:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:19 INFO - '_': '/tools/buildbot/bin/python'} 16:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:19 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:19 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 16:08:19 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 16:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08:21 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 16:08:21 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 16:08:21 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 16:08:21 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 16:08:21 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 16:08:21 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 16:08:21 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:21 INFO - warning: no files found matching '*.txt' 16:08:21 INFO - no previously-included directories found matching 'build' 16:08:21 INFO - no previously-included directories found matching 'dist' 16:08:21 INFO - no previously-included directories found matching '.git*' 16:08:21 INFO - Installing collected packages: jsonschema, functools32 16:08:21 INFO - Running setup.py install for functools32 16:08:21 INFO - warning: no files found matching '*.txt' 16:08:21 INFO - no previously-included directories found matching 'build' 16:08:21 INFO - no previously-included directories found matching 'dist' 16:08:21 INFO - no previously-included directories found matching '.git*' 16:08:21 INFO - Successfully installed jsonschema functools32 16:08:21 INFO - Cleaning up... 16:08:21 INFO - Return code: 0 16:08:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:21 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:21 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 16:08:21 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 16:08:21 INFO - Using env: {'DISPLAY': ':0', 16:08:21 INFO - 'HOME': '/home/cltbld', 16:08:21 INFO - 'LANG': 'en_US.UTF-8', 16:08:21 INFO - 'LANGUAGE': 'en_US:en', 16:08:21 INFO - 'LOGNAME': 'cltbld', 16:08:21 INFO - 'MAIL': '/var/mail/cltbld', 16:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:21 INFO - 'MOZ_NO_REMOTE': '1', 16:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:21 INFO - 'NO_EM_RESTART': '1', 16:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:21 INFO - 'PWD': '/builds/slave/test', 16:08:21 INFO - 'SHELL': '/bin/bash', 16:08:21 INFO - 'SHLVL': '1', 16:08:21 INFO - 'TERM': 'linux', 16:08:21 INFO - 'TMOUT': '86400', 16:08:21 INFO - 'USER': 'cltbld', 16:08:21 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:21 INFO - '_': '/tools/buildbot/bin/python'} 16:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:08:22 INFO - Cleaning up... 16:08:22 INFO - Return code: 0 16:08:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08:22 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')]} 16:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:22 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')]} 16:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:22 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:22 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 16:08:22 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 16:08:22 INFO - Using env: {'DISPLAY': ':0', 16:08:22 INFO - 'HOME': '/home/cltbld', 16:08:22 INFO - 'LANG': 'en_US.UTF-8', 16:08:22 INFO - 'LANGUAGE': 'en_US:en', 16:08:22 INFO - 'LOGNAME': 'cltbld', 16:08:22 INFO - 'MAIL': '/var/mail/cltbld', 16:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:22 INFO - 'MOZ_NO_REMOTE': '1', 16:08:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:22 INFO - 'NO_EM_RESTART': '1', 16:08:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:22 INFO - 'PWD': '/builds/slave/test', 16:08:22 INFO - 'SHELL': '/bin/bash', 16:08:22 INFO - 'SHLVL': '1', 16:08:22 INFO - 'TERM': 'linux', 16:08:22 INFO - 'TMOUT': '86400', 16:08:22 INFO - 'USER': 'cltbld', 16:08:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:22 INFO - '_': '/tools/buildbot/bin/python'} 16:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:22 INFO - Downloading/unpacking blobuploader==1.2.4 16:08:22 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 16:08:22 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 16:08:22 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 16:08:22 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 16:08:22 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 16:08:22 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 16:08:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:24 INFO - Downloading docopt-0.6.1.tar.gz 16:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:25 INFO - Running setup.py install for blobuploader 16:08:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:25 INFO - Running setup.py install for requests 16:08:25 INFO - Running setup.py install for docopt 16:08:25 INFO - Successfully installed blobuploader requests docopt 16:08:25 INFO - Cleaning up... 16:08:25 INFO - Return code: 0 16:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:25 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:25 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 16:08:25 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 16:08:25 INFO - Using env: {'DISPLAY': ':0', 16:08:25 INFO - 'HOME': '/home/cltbld', 16:08:25 INFO - 'LANG': 'en_US.UTF-8', 16:08:25 INFO - 'LANGUAGE': 'en_US:en', 16:08:25 INFO - 'LOGNAME': 'cltbld', 16:08:25 INFO - 'MAIL': '/var/mail/cltbld', 16:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:25 INFO - 'MOZ_NO_REMOTE': '1', 16:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:25 INFO - 'NO_EM_RESTART': '1', 16:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:25 INFO - 'PWD': '/builds/slave/test', 16:08:25 INFO - 'SHELL': '/bin/bash', 16:08:25 INFO - 'SHLVL': '1', 16:08:25 INFO - 'TERM': 'linux', 16:08:25 INFO - 'TMOUT': '86400', 16:08:25 INFO - 'USER': 'cltbld', 16:08:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:25 INFO - '_': '/tools/buildbot/bin/python'} 16:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:25 INFO - Running setup.py (path:/tmp/pip-IWkNpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:25 INFO - Running setup.py (path:/tmp/pip-UVGHly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:26 INFO - Running setup.py (path:/tmp/pip-ZO1e2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:26 INFO - Running setup.py (path:/tmp/pip-0FsVN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:26 INFO - Running setup.py (path:/tmp/pip-mmIJPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:26 INFO - Running setup.py (path:/tmp/pip-uqaSEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:26 INFO - Running setup.py (path:/tmp/pip-6I6dSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:26 INFO - Running setup.py (path:/tmp/pip-ni_E0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:26 INFO - Running setup.py (path:/tmp/pip-62H0Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:26 INFO - Running setup.py (path:/tmp/pip-C_mz5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:26 INFO - Running setup.py (path:/tmp/pip-bSQRGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:26 INFO - Running setup.py (path:/tmp/pip-_709ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:26 INFO - Running setup.py (path:/tmp/pip-IwQeL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:26 INFO - Running setup.py (path:/tmp/pip-IDsN4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:26 INFO - Running setup.py (path:/tmp/pip-ZWRFLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:26 INFO - Running setup.py (path:/tmp/pip-lZrFdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:27 INFO - Running setup.py (path:/tmp/pip-5kreRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:27 INFO - Running setup.py install for manifestparser 16:08:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Running setup.py install for mozcrash 16:08:27 INFO - Running setup.py install for mozdebug 16:08:27 INFO - Running setup.py install for mozdevice 16:08:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Running setup.py install for mozfile 16:08:27 INFO - Running setup.py install for mozhttpd 16:08:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Running setup.py install for mozinfo 16:08:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Running setup.py install for mozInstall 16:08:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:27 INFO - Running setup.py install for mozleak 16:08:28 INFO - Running setup.py install for mozlog 16:08:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Running setup.py install for moznetwork 16:08:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Running setup.py install for mozprocess 16:08:28 INFO - Running setup.py install for mozprofile 16:08:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Running setup.py install for mozrunner 16:08:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Running setup.py install for mozscreenshot 16:08:28 INFO - Running setup.py install for moztest 16:08:28 INFO - Running setup.py install for mozversion 16:08:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:28 INFO - Cleaning up... 16:08:28 INFO - Return code: 0 16:08:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:28 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:28 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 16:08:28 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 16:08:28 INFO - Using env: {'DISPLAY': ':0', 16:08:28 INFO - 'HOME': '/home/cltbld', 16:08:28 INFO - 'LANG': 'en_US.UTF-8', 16:08:28 INFO - 'LANGUAGE': 'en_US:en', 16:08:28 INFO - 'LOGNAME': 'cltbld', 16:08:28 INFO - 'MAIL': '/var/mail/cltbld', 16:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:28 INFO - 'MOZ_NO_REMOTE': '1', 16:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:28 INFO - 'NO_EM_RESTART': '1', 16:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:28 INFO - 'PWD': '/builds/slave/test', 16:08:28 INFO - 'SHELL': '/bin/bash', 16:08:28 INFO - 'SHLVL': '1', 16:08:28 INFO - 'TERM': 'linux', 16:08:28 INFO - 'TMOUT': '86400', 16:08:28 INFO - 'USER': 'cltbld', 16:08:28 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:28 INFO - '_': '/tools/buildbot/bin/python'} 16:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:29 INFO - Running setup.py (path:/tmp/pip-hJQtNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:29 INFO - Running setup.py (path:/tmp/pip-TXioTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:29 INFO - Running setup.py (path:/tmp/pip-J2Ji17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:29 INFO - Running setup.py (path:/tmp/pip-zf6Rqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:29 INFO - Running setup.py (path:/tmp/pip-L1t13c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:29 INFO - Running setup.py (path:/tmp/pip-78c5F8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:29 INFO - Running setup.py (path:/tmp/pip-kdbtSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:29 INFO - Running setup.py (path:/tmp/pip-QgfBO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:29 INFO - Running setup.py (path:/tmp/pip-fGjurz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:29 INFO - Running setup.py (path:/tmp/pip-H7rJbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:29 INFO - Running setup.py (path:/tmp/pip-y5VAoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:29 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)) 16:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:29 INFO - Running setup.py (path:/tmp/pip-9Enb9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:30 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)) 16:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:30 INFO - Running setup.py (path:/tmp/pip-eTiFJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:30 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)) 16:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:30 INFO - Running setup.py (path:/tmp/pip-zx638Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:30 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)) 16:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:30 INFO - Running setup.py (path:/tmp/pip-Z4m2We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:30 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)) 16:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:30 INFO - Running setup.py (path:/tmp/pip-997WoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:30 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)) 16:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:30 INFO - Running setup.py (path:/tmp/pip-_lFm_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:30 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)) 16:08:30 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)) 16:08:30 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)) 16:08:30 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)) 16:08:30 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)) 16:08:30 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)) 16:08:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:32 INFO - Downloading blessings-1.6.tar.gz 16:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:32 INFO - Installing collected packages: blessings 16:08:32 INFO - Running setup.py install for blessings 16:08:32 INFO - Successfully installed blessings 16:08:32 INFO - Cleaning up... 16:08:32 INFO - Return code: 0 16:08:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:32 INFO - Reading from file tmpfile_stdout 16:08:32 INFO - Current package versions: 16:08:32 INFO - argparse == 1.2.1 16:08:32 INFO - blessings == 1.6 16:08:32 INFO - blobuploader == 1.2.4 16:08:32 INFO - docopt == 0.6.1 16:08:32 INFO - functools32 == 3.2.3-2 16:08:32 INFO - jsonschema == 2.5.1 16:08:32 INFO - manifestparser == 1.1 16:08:32 INFO - mozInstall == 1.12 16:08:32 INFO - mozcrash == 0.17 16:08:32 INFO - mozdebug == 0.1 16:08:32 INFO - mozdevice == 0.48 16:08:32 INFO - mozfile == 1.2 16:08:32 INFO - mozhttpd == 0.7 16:08:32 INFO - mozinfo == 0.9 16:08:32 INFO - mozleak == 0.1 16:08:32 INFO - mozlog == 3.3 16:08:32 INFO - moznetwork == 0.27 16:08:32 INFO - mozprocess == 0.23 16:08:32 INFO - mozprofile == 0.28 16:08:32 INFO - mozrunner == 6.12 16:08:32 INFO - mozscreenshot == 0.1 16:08:32 INFO - mozsystemmonitor == 0.3 16:08:32 INFO - moztest == 0.7 16:08:32 INFO - mozversion == 1.4 16:08:32 INFO - psutil == 3.1.1 16:08:32 INFO - requests == 1.2.3 16:08:32 INFO - wsgiref == 0.1.2 16:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:32 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:32 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 16:08:32 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 16:08:32 INFO - Using env: {'DISPLAY': ':0', 16:08:32 INFO - 'HOME': '/home/cltbld', 16:08:32 INFO - 'LANG': 'en_US.UTF-8', 16:08:32 INFO - 'LANGUAGE': 'en_US:en', 16:08:32 INFO - 'LOGNAME': 'cltbld', 16:08:32 INFO - 'MAIL': '/var/mail/cltbld', 16:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:32 INFO - 'MOZ_NO_REMOTE': '1', 16:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:32 INFO - 'NO_EM_RESTART': '1', 16:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:32 INFO - 'PWD': '/builds/slave/test', 16:08:32 INFO - 'SHELL': '/bin/bash', 16:08:32 INFO - 'SHLVL': '1', 16:08:32 INFO - 'TERM': 'linux', 16:08:32 INFO - 'TMOUT': '86400', 16:08:32 INFO - 'USER': 'cltbld', 16:08:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:32 INFO - '_': '/tools/buildbot/bin/python'} 16:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 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)) 16:08:32 INFO - Cleaning up... 16:08:32 INFO - Return code: 0 16:08:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:32 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 0xac7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd88180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, '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': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', '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 16:08:32 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 16:08:32 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 16:08:32 INFO - Using env: {'DISPLAY': ':0', 16:08:32 INFO - 'HOME': '/home/cltbld', 16:08:32 INFO - 'LANG': 'en_US.UTF-8', 16:08:32 INFO - 'LANGUAGE': 'en_US:en', 16:08:32 INFO - 'LOGNAME': 'cltbld', 16:08:32 INFO - 'MAIL': '/var/mail/cltbld', 16:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:32 INFO - 'MOZ_NO_REMOTE': '1', 16:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:32 INFO - 'NO_EM_RESTART': '1', 16:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:32 INFO - 'PWD': '/builds/slave/test', 16:08:32 INFO - 'SHELL': '/bin/bash', 16:08:32 INFO - 'SHLVL': '1', 16:08:32 INFO - 'TERM': 'linux', 16:08:32 INFO - 'TMOUT': '86400', 16:08:32 INFO - 'USER': 'cltbld', 16:08:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:32 INFO - '_': '/tools/buildbot/bin/python'} 16:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:33 INFO - Cleaning up... 16:08:33 INFO - Return code: 0 16:08:33 INFO - Running post-action listener: _resource_record_post_action 16:08:33 INFO - Running post-action listener: _start_resource_monitoring 16:08:33 INFO - Starting resource monitoring. 16:08:33 INFO - [mozharness: 2016-07-28 23:08:33.209689Z] Finished create-virtualenv step (success) 16:08:33 INFO - [mozharness: 2016-07-28 23:08:33.210129Z] Running install step. 16:08:33 INFO - Running pre-action listener: _resource_record_pre_action 16:08:33 INFO - Running main action method: install 16:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:33 INFO - Reading from file tmpfile_stdout 16:08:33 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:33 INFO - Reading from file tmpfile_stdout 16:08:33 INFO - Output received: 16:08:33 INFO - Usage: mozinstall [options] installer 16:08:33 INFO - Options: 16:08:33 INFO - -h, --help show this help message and exit 16:08:33 INFO - -d DEST, --destination=DEST 16:08:33 INFO - Directory to install application into. [default: 16:08:33 INFO - "/builds/slave/test"] 16:08:33 INFO - --app=APP Application being installed. [default: firefox] 16:08:33 INFO - mkdir: /builds/slave/test/build/application 16:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:08:50 INFO - Reading from file tmpfile_stdout 16:08:50 INFO - Output received: 16:08:50 INFO - /builds/slave/test/build/application/firefox/firefox 16:08:50 INFO - Running post-action listener: _resource_record_post_action 16:08:50 INFO - [mozharness: 2016-07-28 23:08:50.886830Z] Finished install step (success) 16:08:50 INFO - [mozharness: 2016-07-28 23:08:50.887109Z] Running run-tests step. 16:08:50 INFO - Running pre-action listener: _resource_record_pre_action 16:08:50 INFO - Running main action method: run_tests 16:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:08:50 INFO - Python 2.7.3 16:08:50 INFO - Return code: 0 16:08:50 INFO - grabbing minidump binary from tooltool 16:08:50 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')]} 16:08:50 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 0xd863b0>, '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 0xd872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd87740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:08:50 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 16:08:50 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 16:08:50 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 16:08:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:50 INFO - Return code: 0 16:08:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:08:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:08:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:08:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:08:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:08:50 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/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:08:50 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/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 16:08:50 INFO - Using env: {'DISPLAY': ':0', 16:08:50 INFO - 'HOME': '/home/cltbld', 16:08:50 INFO - 'LANG': 'en_US.UTF-8', 16:08:50 INFO - 'LANGUAGE': 'en_US:en', 16:08:50 INFO - 'LOGNAME': 'cltbld', 16:08:50 INFO - 'MAIL': '/var/mail/cltbld', 16:08:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:50 INFO - 'MOZ_NO_REMOTE': '1', 16:08:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:50 INFO - 'NO_EM_RESTART': '1', 16:08:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:50 INFO - 'PWD': '/builds/slave/test', 16:08:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:50 INFO - 'SHELL': '/bin/bash', 16:08:50 INFO - 'SHLVL': '1', 16:08:50 INFO - 'TERM': 'linux', 16:08:50 INFO - 'TMOUT': '86400', 16:08:50 INFO - 'USER': 'cltbld', 16:08:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469747155.93915-559281507', 16:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:50 INFO - '_': '/tools/buildbot/bin/python'} 16:08:50 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/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:08:51 INFO - mozversion application_buildid: 20160728145235 16:08:51 INFO - mozversion application_changeset: fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce 16:08:51 INFO - mozversion application_display_name: Nightly 16:08:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:51 INFO - mozversion application_name: Firefox 16:08:51 INFO - mozversion application_remotingname: firefox 16:08:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:08:51 INFO - mozversion application_vendor: Mozilla 16:08:51 INFO - mozversion application_version: 50.0a1 16:08:51 INFO - mozversion platform_buildid: 20160728145235 16:08:51 INFO - mozversion platform_changeset: fc0cdec774074ca00e388c1ecd68cef0b1b7e3ce 16:08:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:08:51 INFO - mozversion platform_version: 50.0a1 16:08:51 INFO - using testdate: 1469747331 16:08:51 INFO - actual date: 1469747331 16:08:51 INFO - starting webserver on 'localhost:52165' 16:08:51 INFO - SUITE-START | Running 2 tests 16:08:51 INFO - TEST-START | dromaeo_css 16:08:51 INFO - operating with platform_type : linux_ 16:08:51 INFO - Initialising browser for dromaeo_css test... 16:08:51 INFO - TEST-INFO | started process 7338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIWA42z/profile http://localhost:52165/getInfo.html) 16:08:54 INFO - PROCESS | 7338 | JavaScript error: , line 0: uncaught exception: undefined 16:08:56 INFO - PROCESS | 7338 | __metrics Screen width/height:1600/1200 16:08:56 INFO - PROCESS | 7338 | colorDepth:24 16:08:56 INFO - PROCESS | 7338 | Browser inner width/height: 1024/697 16:08:56 INFO - PROCESS | 7338 | __metrics 16:08:57 INFO - PROCESS | 7338 | 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" 16:08:57 INFO - PROCESS | 7338 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:58 INFO - TEST-INFO | 7338: exit 0 16:08:58 INFO - Browser initialized. 16:08:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:08:58 INFO - TEST-INFO | started process 7416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIWA42z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:10 INFO - PROCESS | 7416 | RSS: Main: 159526912 16:09:10 INFO - PROCESS | 7416 | 16:10:02 INFO - PROCESS | 7416 | 1469747402210 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] 16:12:14 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52165/tests/dromaeo/cssquery-ext.html) 16:12:15 INFO - PROCESS | 7416 | RSS: Main: 245219328 16:12:15 INFO - PROCESS | 7416 | 16:15:17 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-ext.html (next: http://localhost:52165/tests/dromaeo/cssquery-jquery.html) 16:15:18 INFO - PROCESS | 7416 | RSS: Main: 244592640 16:15:18 INFO - PROCESS | 7416 | 16:18:20 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52165/tests/dromaeo/cssquery-mootools.html) 16:18:21 INFO - PROCESS | 7416 | RSS: Main: 191406080 16:18:21 INFO - PROCESS | 7416 | 16:21:23 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52165/tests/dromaeo/cssquery-prototype.html) 16:21:24 INFO - PROCESS | 7416 | RSS: Main: 205959168 16:21:24 INFO - PROCESS | 7416 | 16:24:27 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52165/tests/dromaeo/cssquery-yui.html) 16:24:27 INFO - PROCESS | 7416 | RSS: Main: 190984192 16:24:27 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | Cycle 1(1): loaded http://localhost:52165/tests/dromaeo/cssquery-yui.html (next: http://localhost:52165/tests/dromaeo/cssquery-dojo.html) 16:27:30 INFO - PROCESS | 7416 | RSS: Main: 279994368 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | __start_tp_report 16:27:30 INFO - PROCESS | 7416 | _x_x_mozilla_page_load 16:27:30 INFO - PROCESS | 7416 | _x_x_mozilla_page_load_details 16:27:30 INFO - PROCESS | 7416 | |i|pagename|runs| 16:27:30 INFO - PROCESS | 7416 | |0;dojo.html;2625.776808971237;2630.9605355919666;2652.801039922005;2667.253169112648;2674.893004279829;4696.154692155411;4723.976380118103;4724.5662509645745;4836.081144582527;4837.080954618625;4737.573618374346;4788.64085193611;4802.327674125625;4802.471728109905;4836.951630483694;4347.978260108695;4355.36847157162;4451.2655411857;4512.255477846152;4568.132054909556;4475.641948644101;4504.729716217021;4505.43682039745;4531.297648864414;4531.932021019687;4265.829366825324;4339.913201735948;4340;4369.038811461473;4461.486929003174;4345.869623911288;4348.977990172307;4356.782160891975;4406.911861762779;4406.955930440687;4314.115606300732;4391.748351719744;4396.472423309191;4431.667624928111;4435.223835828717;4623.56076172763;4772.188727916263;4825.059113472874;4877.439094504142;4885.071836351118;5093.286939828427;5140.33175687162;5154.4587818278915;5212.348456442945;5245.134552798783;5191.2472691459525;5195.844124676266;5227.660202086852;5231.633785635007;5245.396779370345;4698.248280275208;4793.448753393334;4838.153323168467;4887.535684110004;4893.388326459192;5269.631125821155;5308.654937429054;5363;5366.034113859543;5394.514493695587;5139.511746384088;5153.2785410042625;5154.9484505155215;5175.17197248446;5177.715225662625;4988.451270360258;5094.5160209780015;5271.630985830955;5278.577713782888;5282.947170528246;5048.5203905628905;5077.339945807022;5087.084324821493;5128.3076784633595;5131.846044618593;5052.368453943257;5059.316992206079;5066.518680725325;5107.055194788902;5112.769925353368;4259.787010649455;4281.443412356436;4360.803763830635;4371.650267978618;4404.493483249467;3781.640744129303;3792.089898424358;3825.8087095645665;3871.032241939515;3888.0862997195636;4415.823367065345;4418.270985287456;4435.090806384684;4454.28731402974;4482.21561226784;5003.449620541737;5176.275321454924;5185.507376799198;5192.636515443883;5243.056249875059;4299.591538803808;4311.892202694895;4325.6106950374615;4354;4367.803448844809;4545.38637283969;4547.431571053618;4561.498235194126;4582.587567118975;4591.265397536378;4712.363830882787;4749.4063242094735;4754.405699287589;4757.452892917289;4807.278908163804;4979.9004019918675;5034.345535080416;5073.6702114363925;5080.872978175576;5086.287919691172;4831.661783675109;4843.104025755247;4845.781939812788;4865.70805751656;4874.8537543873745;5026.9694712583105;5071.93489367237;5079.87300317495;5080.593552515864;5105.2086926526445;5042.899142017212;5118.667286626357;5159.122949098737;5182.6113041521285;5189.76646050921;5093.261477085876;5094.770735316845;5096.46487118862;5116.692998420107;5124;5095.745212739423;5109.489051095009;5110.770015349392;5114.2840002400435;5159.896802063862;4836.758162091952;4898.559129678346;4946.678465899705;5032.798688052583;5045.091883461012;5055;5057.6459647824295;5061.620378471555;5083.491650834887;5115.718635475085;4899.31409602663;4916.311716359784;4923.384576927884;4927.679700819579;4964.950350496449 16:27:30 INFO - PROCESS | 7416 | |1;ext.html;574.3309044962617;575.7006356694519;585.2245774348988;587.0284678856491;590.3299754778325;26979.730202697967;39002.41496377557;40408.19183616329;40447.595524044824;44886.32670509945;40121.38604060526;40668.59331406692;40709.79645101778;40760.38859417111;40877;7722.691092356299;7819.804504887395;7826.178251283619;7826.491278066936;7863.095743989428;7325.12098548175;9770.218382529381;9860.802783944317;9891.109800117987;10313.89686103137;10184.847227291597;10268.794624107513;10278.948605256963;10283.485825708685;10284.691459256233;10083.495825208747;10148.695539133838;10149.0865822076;10152.39085654863;10156;9976.401415914996;10051;10055.245856560787;10056.748581285454;10062.144717699004;38452.80773596142;38829.22341553153;38854.41718374227;38903;38939.22121557581;3109.5957525521762;3138.7489000879873;3159.636641786201;3270.7546933980047;3460.9963110697868;27821.887124514997;28430.857845710845;28535.429291414264;28541.857290713622;28575.9998400055;3377.9493307600405;3379.3158124231913;3467.2025434150287;3593.3531964246426;3594.856205751767;237142;238444;239139;239669.80165099236;240892;28887.277818054717;52659.73670131625;52948.735256324246;53019.46980530145;53062.734686327094;30979.845100774804;32776.180595485304;32887.34225315528;32929.835350823094;32954.3409131816;13546.661333466742;13710.17738935667;13711.588652340446;13716;13816;10697.679069627768;11510.482028308747;11739.06087512997;11799.88200118005;11819.349935753444;7625.084989801259;7678.57767822764;7821.960890195513;7831.334336581454;7850.921490785019;3045.33002739397;3123.2035830862983;3125.7499400048396;3138.5919830422354;3144.638366587826;808.2523665609287;884.9646014159362;956.039180623469;958.8657587937695;989.4360214677708;26237;26721.732782672312;26855.194344169708;27129.86435067812;27245.318867028087;938.4979036215728;943.6084025129632;955.9569819358006;1001.1090129784498;1082.3938594387012;13885.166889986633;14391.78412323816;14496.420143194155;14768.63078423048;14820.3330850112;314771;320678;321030;321884;321899;46505.30242046372;46651;46657.76671116623;47091;47127;1051.358671210561;1140.3386036099043;1152.8501294831785;1180.477184432073;1194.6714653470192;27624.447511049264;29241;29300;29336.119916402542;29812.552811708276;42112.789436052626;42518.36222456604;42636;42662;42682.1463570733;299039;302640;302849;302924;303036;25246.621300680137;25723.871380642977;25821.483570328863;26075.217743467725;27326.316842079108;40685.59314406818;40859.59140408558;41071;41433.37849932314;42195.57804421917;35580.64419355773;35671;35731;36422;36457.45313820348;35666.821665891504;35814.28371432609;35898;36402.817985909904;36579;35298.29403411969;35467.645323546436;35559.288814224084;35610.82194589011;35851.82074089613;31017.0694879154;32363.838180808943;32597.837010814794;32716.509252360756;32813;24906.377340566632;24984.75015249897;25161.74838251594;25171;25544.616830747924 16:27:30 INFO - PROCESS | 7416 | |2;jquery.html;344.1980186166233;363.2843298701557;368.9483472313876;372.099519163624;388.3572687202679;13039.152455090425;16262.186890655465;16502.834971650296;16585.336586536523;17612.911935440305;16579.83420165798;16775.91612041941;16849.494515164533;16947.57631059224;17111.40110096147;4941.283513890484;5092.745362731867;5129.563987061104;5137.049645815518;5181.5077567631015;3751.5685696144883;5089.440161582223;5174.560162386183;5738.827835164931;6049.0623953287095;6061.696915154247;6086.417531441835;6094.329623741407;6104.023356262999;6111;5986.221791167142;5987.64074155552;6042.9395706042815;6075.301340345828;6157.969210153965;5196.558292545138;5391.16436952273;5394.433584473613;5394.811181608664;5396.946030539723;20421.387358379125;20594.27920022792;20602.484937876525;20713.482162946046;20714.171433142656;1841.8250266224686;1859.349227770283;1889.5842914558912;1908.4942490240098;2026.6453370660076;12618.873811261861;13223.801642975362;13572;13596.32018399086;13826.861731382658;1711.2727090986282;1790.543411430077;1806.6477036977794;1841.5212044868297;1847.7782666080022;68399.65800170967;69262;69291.99999999949;70656.6467167666;71380;9531.952340238255;9574.664886728931;9788.363756355815;9977.700668979942;10371.481425928674;13416.731665366638;13438.529651462151;13445.865541344658;13476.39356228972;13484.865151348557;7367.226441223702;7397.26027397256;7544.811379715551;7582.696692132363;7632.618369081523;7080.6105664187935;7093.184283807428;7097.396721278646;7315.817104572428;7326;6010.759569617167;6147.877042459215;6174.475169610543;6186.980800726217;6281.120643109968;1737.261663792883;1789.579448829524;1794.6231291428946;1813.3925135079896;1819.8908065516111;805.3235282362843;880.0495464897895;911.1480765484293;966.0387914025449;970.427447805798;16645.33418663264;16834;17448.12759362027;17811.287548498174;17863.285468581373;798.7883210949103;846.4328899637258;861.2076889261895;867.8828358171565;927.8979312275644;9508.857367139499;9612.903870961341;9761.9023809761;10159.796804063877;10397.948010259901;392357;394656;395439;397863.0106849505;400592;78307;78488;79662.00844978806;81011;82293.58853205695;892.4466830565091;904.5477261369184;932.8973812880578;936.6908920056366;941.0072373645733;15225.77161342603;15842.52472425829;16376.918115409348;16423;16615.252313646157;28104.29739256535;29654.851725741235;35850.92447226588;38184;39225.6077439222;51500.742496287276;52191;52310;52380.47619523756;52482;7044.401225895856;7450;7467.6639551221415;7484.363829074589;7871.921280787119;8643;12060.698482538006;12206.389680516117;12307.507699691912;12479.376031198222;10288.459157649857;10344.27590068688;10435.165186784887;10562.31344962575;10563.154947604327;6156.753729850756;10466.790664186827;10742.355458672504;10807.243492955418;10824.458777061272;10374.533146008598;10455.84316235242;10503.632372866912;10519.105876000625;10891.455427228766;7668.923310766821;7749.457537972288;7767.53394796314;7822.882656760267;7824.178461261486;9255.444673319622;10168.593256269962;10427.478626068818;10719.356838589709;10885.673429797118 16:27:30 INFO - PROCESS | 7416 | |3;mootools.html;1258.5657948007934;1344.2472215559285;1390.3117956611475;1452.4698485052959;1696.3638635511675;6773.559718618292;7475.102987641484;7727.884081738778;7735.651895664701;7956.880646790303;7774.144844067148;7777;7820.687172513107;7833.647485863135;7842.72550460734;2137.1451419432233;2161.329987703809;2161.6541353383464;2163.491579478821;2165.1880544795704;2126.192047022137;2164;2206.106526856626;2211.469247380625;2215.0253888289103;2140.56118495708;2147.24846303794;2157.805797478227;2158.9568208635897;2182.3998400439846;2128.8190503807195;2135.9786402136015;2136.6794980752857;2138;2138.994672503921;2079.105984426696;2125.160561578183;2130.4886827161527;2131.520407908208;2151.2363111095647;7706.422018348646;7789.688412463494;7866.488678235954;7867.0952840423515;7875.015623047119;4673.065386922629;4768.094062128219;4769.236922092483;4770.904581908377;4772.832950846704;2843.445528122017;2903.5789810477363;3017;3017.4719424100913;3017.8189308641554;867.2758246863813;872.2062922740339;872.3370238618633;880.5949263338872;885.5749240364597;10891.564337426493;10974.451277436096;11000.394978276192;11000.559977600886;11011.339319640847;3953.7825419601622;4135.31767258399;4235.301175306102;4290.141971605692;4292.248856450109;8585.31317494599;8594;8594.699185528476;8595.312375009984;8597.785055373666;4650.37219975299;4698.8355407560575;4700.130475861976;4702.506236845151;4703.0123674028155;3693.7414380993073;4190.790460476964;4334.046509767846;4379.540148284448;4383.320585309282;3349.112485191426;3362.402858642174;3474.635163307816;3482.094655389617;3505.544279243733;1002.1682003936716;1010.0707349238718;1010.6563768318659;1027.8817935937464;1035.9689209323733;845.2941793602411;852.4416507187802;859.8108416148438;884.6151923913119;903.7559858838208;5377.7311134443125;5504.697241651747;5641.041023026095;5655.830325090254;5730.19777231188;814.5112932240609;832.9958350208332;865.446114486729;883.1168831168832;927.8608208768738;3614.783113013175;3696.796676182782;3739.2521495700967;3837.520309961255;3839.347310957144;9741.464219467856;9987.650432234837;10083.697489075339;10089.445080520494;10145.08694217524;9980.900190998142;10015.39907605546;10033.347832390871;10034;10049.397036177852;7305;7399.593022383713;7411.2959268870445;7413.740519081806;7418.480706350504;5286.074936886106;5373.946260537345;5535.25274087993;5568.387477377566;5683.204351390891;9090.863637045317;9096.590653420479;9126.224270936778;9169.633214671338;9250.95374523123;9076;9288.442693438416;9340;9445.338826282368;9491.240700744062;6435.2599451063725;6594.8351291218105;6633.436157926437;6660.766873159416;6809.182898052265;7635.121960974559;7704.537727736129;7707.036620422447;7709.807254818675;7711.614419279126;6821.079154314302;6892.862142757217;6898.827529311808;6940.895886561605;7062.223155452999;6671.466282697275;6744.426723728342;6757.7634782784335;6801.081853949653;6822.317768223138;6534.444572211415;6559.409653131241;6602.372774586406;6617.338266173344;6619.371159739817;4592.15045216636;4673.696209746356;4726.432828060655;4815.109204797123;4915.26271059344;4663.790129444115;4695.154872123051;4839.854804355875;4857.975710121426;4912.238603016538 16:27:30 INFO - PROCESS | 7416 | |4;prototype.html;289.33741731435003;293.8016838633922;302.6625312776254;303.80404639007827;304.26216425168957;4972.2790195421685;5006.524380183877;5007.824726134581;5015.4984501549825;5017.799288028475;4692.724028846007;4802.255650374189;4851.878703032426;4866.343043689101;4932.753362331878;3752.1182522107283;3789.5831458539537;3803.8478460861534;3839.001859516532;3881.611838816124;3894.6689531389725;3896.688264938798;3902.2585708715396;3915.921681566367;3928.430377595247;3615.7830530168144;3784.8107594620296;3871.5160604924386;3888.358420860569;3898.8245528951265;3799.8670046548377;3802.923941521182;3812.9618703812885;3821.464994900716;3829.5404551454003;3272.705456508902;3318.3197444523817;3322.0864262327814;3329.1344250494803;3330.417176994041;4826.831060912851;4849.9757501212625;4862.149123903303;4862.975685121587;4875.439324477659;4153.92303848076;4341.717788343779;4349.608535231812;4353.36876152956;4361.127774445092;5148.3564554430695;5172.27588137661;5189.299444574972;5198.636095473318;5212.29633999409;3651.963480365189;3665.7617254878614;3666.9633303666888;3674.430463278196;3681.723870709681;5168.198929165986;5371.060064488699;5394.811181608625;5447.4824891635235;5468.91796623051;3697.5378077740284;3704.5369328833895;3715.275521273326;3722.1575320216666;3728.179800443898;3752.75607085544;3754.9436758448646;3759.7556158850134;3764.4189440435084;3767.8334957399607;4240.703150779477;4313.158934007901;4328.653707703439;4329.242382583098;4352.325389564622;5125.1287281162295;5147.3051138096625;5166.483351664803;5169;5196.402413722471;1281.628327784932;1300.024981264052;1303.1985329022773;1304.504288370432;1308.5354699081688;815.5881279953585;865.4288169807896;878.8004374029532;891.1133422244778;896.3859756067114;4515.14212299662;4526.6152377047665;4528.139653465897;4534.206513860061;4543.909121817545;5238.345206849144;5240.9213861792105;5253.343332083489;5314.990151871132;5320.095583750772;4291.6137547620865;4455.487618923865;4475.440569928759;4487.237169681097;4489.842855500042;2772.6672799263815;2786.275568352243;2791.9022834200705;2796.804223704321;2801.173653772142;4925.586462633284;4947.678400903929;4950.3069570260195;4967.832559348551;4971.37857767779;5253.425821347589;5298.682079075268;5324.653897496651;5337.2527846101575;5364.015314333721;4932.630052745986;4932.827351042696;4952.158133117378;4956.157453232959;4961.709955411691;5252.448492908343;5256.894862102813;5263.921041184309;5271.341082364705;5277.498637629419;4907.877303067452;5045.7981680732355;5111.974440127924;5117.74411279442;5156.1492353761205;4942.182955715208;4945.530174633404;4949.950500494949;4958.380202474691;4963.602911767122;3719.20037192005;3723.9813800930824;3740.7194460415035;3748.981255093707;3751.437284407361;5053.317802096817;5211.348581427322;5287.589504769326;5295.391030031442;5308.283381743415;5166.682495963505;5177.326947496952;5197.882455272134;5221.373435187802;5223.503767142115;5230.529252367305;5252.135491900473;5254.710990895537;5263.578913686973;5276.366835979707;4852;4888.706677599355;5095.668781529189;5126.077306084941;5130.179171332718;4983.8504844854715;5249.7112658804135;5255.816046438356;5286;5298.602604804732;5069.88462538241;5102.821401250939;5107.744612769272;5123.692578445305;5136.563392111712 16:27:30 INFO - PROCESS | 7416 | |5;yui.html;595.9046552551591;633.8288662061241;648.5816648261871;666.0841343153164;670.2560158224371;11473.311601303927;12022.57920972766;12083.154179207459;12140.514379424823;12183.147179697413;12283.44724487398;12450.128491005633;12458.937705311484;12463.750724985495;12486.750264994695;619.9163112979735;739.8742213823635;753.3333000294751;771.6450432800918;775.7246177606953;756.3382040714375;758.8709919313729;759.5784339691469;759.6125975752378;760.0309605253291;808.6320724070533;810.0198759501011;810.0239211749828;810.7486679129489;811.419834818105;652.161971866152;668.6422763821381;670.3631550027445;674.1741366825648;816.1145157504116;570.57207094679;589.0310439327326;589.7581991383512;591.2668291318734;592.052715654953;11874.940625296904;11891.405429728566;11952.402379881058;11987.64037078889;11988.940055299669;11127.332360058423;11385.772284554345;11427.885721142848;11582;12257.877421225852;10249.948750256275;10274.075333220046;10321.84517232242;10373.585056597727;10470.319429237075;3903.512060992376;3996.0609256824623;3999.200159968018;4015.4779878615886;4085.101277718897;57135.42864571343;57202.42797572013;57489.13766293467;57642.42357576412;58419.494830310316;9257.981622021573;9259.629614815467;9277.721668349961;9280.953595232115;9297.581608827619;16943.661126777395;17055.658886822195;17069.7439538407;17171.828281717273;17230.569235768955;6579.638119903356;6641.966790166019;6663.03386009026;6708.932910670928;6760;3520.2431477232517;3585.6414358563934;3590.1922067534597;3595.8921232363073;3604.13500759821;2524.684414448194;2594.8702564871683;2607.361196506868;2608.595667671514;2617.9214623560943;477.3388856433864;480.9158397280462;482.0383335246136;493.84937594034164;499.37328652541555;446.13004640950277;468.1409613359455;509.6967304453844;536.7584586935894;539.2746755613693;6494.577852439576;6529.967350163314;6616.139901812733;6634.767783127664;6747;494.88617617948074;513.2711549599578;514.9253358263031;518.0959226150392;520.0275484843368;3178.8251646159683;3188.87244510217;3208.374366998413;3229.531717900938;3263.640999490054;76254.61872690602;93614;99517;100248.49875750575;100322.4983875076;47959;78581;78592;78614;79129.60435197788;49971.50028499741;67723;74025.25974740292;74731;74735;7481.925180748123;8091.676332946617;8195.262426381654;8228.42401031922;8350.498970061815;44819.32771008372;45598.31602525898;45695.77152114218;46412.76793616146;46621.53378466172;59038.40961590501;61950.38049619627;62522;63516.68241658947;64099.11553980175;10299.845502317621;10465.581376744845;10645.254832161674;10773.19201059908;10813.189010824377;11437.142214334124;11548.538058477567;11667.00830429423;11883.881161188623;12042.518299268282;10289.84565231507;10605.8939410608;10701.946490267499;10906.072983796148;11057.668269951915;9841.940040026631;10172;10542.947285263524;10902.454877255946;11013.504392302526;10283.382997020202;10545.367277963347;10717.981791729773;10918.399488028235;10933.890661093288;8826.382153249211;8839.425437346552;9026.277897768294;9140.360174787702;9386.530673466435;9367.110124538158;9693.369930954465;9789.755256118655;9858;9982.700518984442 16:27:30 INFO - PROCESS | 7416 | __end_tp_report 16:27:30 INFO - PROCESS | 7416 | __start_cc_report 16:27:30 INFO - PROCESS | 7416 | _x_x_mozilla_cycle_collect,971 16:27:30 INFO - PROCESS | 7416 | __end_cc_report 16:27:30 INFO - PROCESS | 7416 | __startTimestamp1469748450885__endTimestamp 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | ------- Summary: start ------- 16:27:30 INFO - PROCESS | 7416 | Number of tests: 6 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#0] dojo.html Cycles:165 Average:4753.45 Median:4877.44 stddev:514.56 (10.5%) stddev-sans-first:488.31 16:27:30 INFO - PROCESS | 7416 | Values: 2625.8 2631.0 2652.8 2667.3 2674.9 4696.2 4724.0 4724.6 4836.1 4837.1 4737.6 4788.6 4802.3 4802.5 4837.0 4348.0 4355.4 4451.3 4512.3 4568.1 4475.6 4504.7 4505.4 4531.3 4531.9 4265.8 4339.9 4340.0 4369.0 4461.5 4345.9 4349.0 4356.8 4406.9 4407.0 4314.1 4391.7 4396.5 4431.7 4435.2 4623.6 4772.2 4825.1 4877.4 4885.1 5093.3 5140.3 5154.5 5212.3 5245.1 5191.2 5195.8 5227.7 5231.6 5245.4 4698.2 4793.4 4838.2 4887.5 4893.4 5269.6 5308.7 5363.0 5366.0 5394.5 5139.5 5153.3 5154.9 5175.2 5177.7 4988.5 5094.5 5271.6 5278.6 5282.9 5048.5 5077.3 5087.1 5128.3 5131.8 5052.4 5059.3 5066.5 5107.1 5112.8 4259.8 4281.4 4360.8 4371.7 4404.5 3781.6 3792.1 3825.8 3871.0 3888.1 4415.8 4418.3 4435.1 4454.3 4482.2 5003.4 5176.3 5185.5 5192.6 5243.1 4299.6 4311.9 4325.6 4354.0 4367.8 4545.4 4547.4 4561.5 4582.6 4591.3 4712.4 4749.4 4754.4 4757.5 4807.3 4979.9 5034.3 5073.7 5080.9 5086.3 4831.7 4843.1 4845.8 4865.7 4874.9 5027.0 5071.9 5079.9 5080.6 5105.2 5042.9 5118.7 5159.1 5182.6 5189.8 5093.3 5094.8 5096.5 5116.7 5124.0 5095.7 5109.5 5110.8 5114.3 5159.9 4836.8 4898.6 4946.7 5032.8 5045.1 5055.0 5057.6 5061.6 5083.5 5115.7 4899.3 4916.3 4923.4 4927.7 4965.0 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#1] ext.html Cycles:180 Average:43726.06 Median:17934.30 stddev:75768.92 (422.5%) stddev-sans-first:75912.20 16:27:30 INFO - PROCESS | 7416 | Values: 574.3 575.7 585.2 587.0 590.3 26979.7 39002.4 40408.2 40447.6 44886.3 40121.4 40668.6 40709.8 40760.4 40877.0 7722.7 7819.8 7826.2 7826.5 7863.1 7325.1 9770.2 9860.8 9891.1 10313.9 10184.8 10268.8 10278.9 10283.5 10284.7 10083.5 10148.7 10149.1 10152.4 10156.0 9976.4 10051.0 10055.2 10056.7 10062.1 38452.8 38829.2 38854.4 38903.0 38939.2 3109.6 3138.7 3159.6 3270.8 3461.0 27821.9 28430.9 28535.4 28541.9 28576.0 3377.9 3379.3 3467.2 3593.4 3594.9 237142.0 238444.0 239139.0 239669.8 240892.0 28887.3 52659.7 52948.7 53019.5 53062.7 30979.8 32776.2 32887.3 32929.8 32954.3 13546.7 13710.2 13711.6 13716.0 13816.0 10697.7 11510.5 11739.1 11799.9 11819.3 7625.1 7678.6 7822.0 7831.3 7850.9 3045.3 3123.2 3125.7 3138.6 3144.6 808.3 885.0 956.0 958.9 989.4 26237.0 26721.7 26855.2 27129.9 27245.3 938.5 943.6 956.0 1001.1 1082.4 13885.2 14391.8 14496.4 14768.6 14820.3 314771.0 320678.0 321030.0 321884.0 321899.0 46505.3 46651.0 46657.8 47091.0 47127.0 1051.4 1140.3 1152.9 1180.5 1194.7 27624.4 29241.0 29300.0 29336.1 29812.6 42112.8 42518.4 42636.0 42662.0 42682.1 299039.0 302640.0 302849.0 302924.0 303036.0 25246.6 25723.9 25821.5 26075.2 27326.3 40685.6 40859.6 41071.0 41433.4 42195.6 35580.6 35671.0 35731.0 36422.0 36457.5 35666.8 35814.3 35898.0 36402.8 36579.0 35298.3 35467.6 35559.3 35610.8 35851.8 31017.1 32363.8 32597.8 32716.5 32813.0 24906.4 24984.8 25161.7 25171.0 25544.6 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#2] jquery.html Cycles:180 Average:24742.95 Median:202766.64 stddev:65412.53 (32.3%) stddev-sans-first:65570.38 16:27:30 INFO - PROCESS | 7416 | Values: 344.2 363.3 368.9 372.1 388.4 13039.2 16262.2 16502.8 16585.3 17612.9 16579.8 16775.9 16849.5 16947.6 17111.4 4941.3 5092.7 5129.6 5137.0 5181.5 3751.6 5089.4 5174.6 5738.8 6049.1 6061.7 6086.4 6094.3 6104.0 6111.0 5986.2 5987.6 6042.9 6075.3 6158.0 5196.6 5391.2 5394.4 5394.8 5396.9 20421.4 20594.3 20602.5 20713.5 20714.2 1841.8 1859.3 1889.6 1908.5 2026.6 12618.9 13223.8 13572.0 13596.3 13826.9 1711.3 1790.5 1806.6 1841.5 1847.8 68399.7 69262.0 69292.0 70656.6 71380.0 9532.0 9574.7 9788.4 9977.7 10371.5 13416.7 13438.5 13445.9 13476.4 13484.9 7367.2 7397.3 7544.8 7582.7 7632.6 7080.6 7093.2 7097.4 7315.8 7326.0 6010.8 6147.9 6174.5 6187.0 6281.1 1737.3 1789.6 1794.6 1813.4 1819.9 805.3 880.0 911.1 966.0 970.4 16645.3 16834.0 17448.1 17811.3 17863.3 798.8 846.4 861.2 867.9 927.9 9508.9 9612.9 9761.9 10159.8 10397.9 392357.0 394656.0 395439.0 397863.0 400592.0 78307.0 78488.0 79662.0 81011.0 82293.6 892.4 904.5 932.9 936.7 941.0 15225.8 15842.5 16376.9 16423.0 16615.3 28104.3 29654.9 35850.9 38184.0 39225.6 51500.7 52191.0 52310.0 52380.5 52482.0 7044.4 7450.0 7467.7 7484.4 7871.9 8643.0 12060.7 12206.4 12307.5 12479.4 10288.5 10344.3 10435.2 10562.3 10563.2 6156.8 10466.8 10742.4 10807.2 10824.5 10374.5 10455.8 10503.6 10519.1 10891.5 7668.9 7749.5 7767.5 7822.9 7824.2 9255.4 10168.6 10427.5 10719.4 10885.7 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#3] mootools.html Cycles:180 Average:5211.20 Median:4913.75 stddev:2950.23 (60.0%) stddev-sans-first:2943.55 16:27:30 INFO - PROCESS | 7416 | Values: 1258.6 1344.2 1390.3 1452.5 1696.4 6773.6 7475.1 7727.9 7735.7 7956.9 7774.1 7777.0 7820.7 7833.6 7842.7 2137.1 2161.3 2161.7 2163.5 2165.2 2126.2 2164.0 2206.1 2211.5 2215.0 2140.6 2147.2 2157.8 2159.0 2182.4 2128.8 2136.0 2136.7 2138.0 2139.0 2079.1 2125.2 2130.5 2131.5 2151.2 7706.4 7789.7 7866.5 7867.1 7875.0 4673.1 4768.1 4769.2 4770.9 4772.8 2843.4 2903.6 3017.0 3017.5 3017.8 867.3 872.2 872.3 880.6 885.6 10891.6 10974.5 11000.4 11000.6 11011.3 3953.8 4135.3 4235.3 4290.1 4292.2 8585.3 8594.0 8594.7 8595.3 8597.8 4650.4 4698.8 4700.1 4702.5 4703.0 3693.7 4190.8 4334.0 4379.5 4383.3 3349.1 3362.4 3474.6 3482.1 3505.5 1002.2 1010.1 1010.7 1027.9 1036.0 845.3 852.4 859.8 884.6 903.8 5377.7 5504.7 5641.0 5655.8 5730.2 814.5 833.0 865.4 883.1 927.9 3614.8 3696.8 3739.3 3837.5 3839.3 9741.5 9987.7 10083.7 10089.4 10145.1 9980.9 10015.4 10033.3 10034.0 10049.4 7305.0 7399.6 7411.3 7413.7 7418.5 5286.1 5373.9 5535.3 5568.4 5683.2 9090.9 9096.6 9126.2 9169.6 9251.0 9076.0 9288.4 9340.0 9445.3 9491.2 6435.3 6594.8 6633.4 6660.8 6809.2 7635.1 7704.5 7707.0 7709.8 7711.6 6821.1 6892.9 6898.8 6940.9 7062.2 6671.5 6744.4 6757.8 6801.1 6822.3 6534.4 6559.4 6602.4 6617.3 6619.4 4592.2 4673.7 4726.4 4815.1 4915.3 4663.8 4695.2 4839.9 4858.0 4912.2 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#4] prototype.html Cycles:180 Average:4266.48 Median:4898.29 stddev:1245.03 (25.4%) stddev-sans-first:1212.21 16:27:30 INFO - PROCESS | 7416 | Values: 289.3 293.8 302.7 303.8 304.3 4972.3 5006.5 5007.8 5015.5 5017.8 4692.7 4802.3 4851.9 4866.3 4932.8 3752.1 3789.6 3803.8 3839.0 3881.6 3894.7 3896.7 3902.3 3915.9 3928.4 3615.8 3784.8 3871.5 3888.4 3898.8 3799.9 3802.9 3813.0 3821.5 3829.5 3272.7 3318.3 3322.1 3329.1 3330.4 4826.8 4850.0 4862.1 4863.0 4875.4 4153.9 4341.7 4349.6 4353.4 4361.1 5148.4 5172.3 5189.3 5198.6 5212.3 3652.0 3665.8 3667.0 3674.4 3681.7 5168.2 5371.1 5394.8 5447.5 5468.9 3697.5 3704.5 3715.3 3722.2 3728.2 3752.8 3754.9 3759.8 3764.4 3767.8 4240.7 4313.2 4328.7 4329.2 4352.3 5125.1 5147.3 5166.5 5169.0 5196.4 1281.6 1300.0 1303.2 1304.5 1308.5 815.6 865.4 878.8 891.1 896.4 4515.1 4526.6 4528.1 4534.2 4543.9 5238.3 5240.9 5253.3 5315.0 5320.1 4291.6 4455.5 4475.4 4487.2 4489.8 2772.7 2786.3 2791.9 2796.8 2801.2 4925.6 4947.7 4950.3 4967.8 4971.4 5253.4 5298.7 5324.7 5337.3 5364.0 4932.6 4932.8 4952.2 4956.2 4961.7 5252.4 5256.9 5263.9 5271.3 5277.5 4907.9 5045.8 5112.0 5117.7 5156.1 4942.2 4945.5 4950.0 4958.4 4963.6 3719.2 3724.0 3740.7 3749.0 3751.4 5053.3 5211.3 5287.6 5295.4 5308.3 5166.7 5177.3 5197.9 5221.4 5223.5 5230.5 5252.1 5254.7 5263.6 5276.4 4852.0 4888.7 5095.7 5126.1 5130.2 4983.9 5249.7 5255.8 5286.0 5298.6 5069.9 5102.8 5107.7 5123.7 5136.6 16:27:30 INFO - PROCESS | 7416 | 16:27:30 INFO - PROCESS | 7416 | [#5] yui.html Cycles:180 Average:16624.60 Median:25240.60 stddev:23902.95 (94.7%) stddev-sans-first:23939.70 16:27:30 INFO - PROCESS | 7416 | Values: 595.9 633.8 648.6 666.1 670.3 11473.3 12022.6 12083.2 12140.5 12183.1 12283.4 12450.1 12458.9 12463.8 12486.8 619.9 739.9 753.3 771.6 775.7 756.3 758.9 759.6 759.6 760.0 808.6 810.0 810.0 810.7 811.4 652.2 668.6 670.4 674.2 816.1 570.6 589.0 589.8 591.3 592.1 11874.9 11891.4 11952.4 11987.6 11988.9 11127.3 11385.8 11427.9 11582.0 12257.9 10249.9 10274.1 10321.8 10373.6 10470.3 3903.5 3996.1 3999.2 4015.5 4085.1 57135.4 57202.4 57489.1 57642.4 58419.5 9258.0 9259.6 9277.7 9281.0 9297.6 16943.7 17055.7 17069.7 17171.8 17230.6 6579.6 6642.0 6663.0 6708.9 6760.0 3520.2 3585.6 3590.2 3595.9 3604.1 2524.7 2594.9 2607.4 2608.6 2617.9 477.3 480.9 482.0 493.8 499.4 446.1 468.1 509.7 536.8 539.3 6494.6 6530.0 6616.1 6634.8 6747.0 494.9 513.3 514.9 518.1 520.0 3178.8 3188.9 3208.4 3229.5 3263.6 76254.6 93614.0 99517.0 100248.5 100322.5 47959.0 78581.0 78592.0 78614.0 79129.6 49971.5 67723.0 74025.3 74731.0 74735.0 7481.9 8091.7 8195.3 8228.4 8350.5 44819.3 45598.3 45695.8 46412.8 46621.5 59038.4 61950.4 62522.0 63516.7 64099.1 10299.8 10465.6 10645.3 10773.2 10813.2 11437.1 11548.5 11667.0 11883.9 12042.5 10289.8 10605.9 10701.9 10906.1 11057.7 9841.9 10172.0 10542.9 10902.5 11013.5 10283.4 10545.4 10718.0 10918.4 10933.9 8826.4 8839.4 9026.3 9140.4 9386.5 9367.1 9693.4 9789.8 9858.0 9982.7 16:27:30 INFO - PROCESS | 7416 | -------- Summary: end -------- 16:27:30 INFO - PROCESS | 7416 | 16:27:31 INFO - TEST-INFO | 7416: exit 0 16:27:31 INFO - TEST-OK | dromaeo_css | took 1120602ms 16:27:31 INFO - TEST-START | kraken 16:27:31 INFO - operating with platform_type : linux_ 16:27:31 INFO - Initialising browser for kraken test... 16:27:31 INFO - TEST-INFO | started process 7602 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp37TUBt/profile http://localhost:52165/getInfo.html) 16:27:35 INFO - PROCESS | 7602 | JavaScript error: , line 0: uncaught exception: undefined 16:27:36 INFO - PROCESS | 7602 | __metrics Screen width/height:1600/1200 16:27:36 INFO - PROCESS | 7602 | colorDepth:24 16:27:36 INFO - PROCESS | 7602 | Browser inner width/height: 1024/697 16:27:36 INFO - PROCESS | 7602 | __metrics 16:27:37 INFO - PROCESS | 7602 | 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" 16:27:37 INFO - PROCESS | 7602 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:27:38 INFO - TEST-INFO | 7602: exit 0 16:27:38 INFO - Browser initialized. 16:27:38 INFO - Running cycle 1/1 for kraken test... 16:27:38 INFO - TEST-INFO | started process 7681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp37TUBt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:50 INFO - PROCESS | 7681 | RSS: Main: 159391744 16:27:50 INFO - PROCESS | 7681 | 16:27:53 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52165/tests/kraken/driver.html?testName=audio-beat-detection) 16:27:54 INFO - PROCESS | 7681 | RSS: Main: 215416832 16:27:54 INFO - PROCESS | 7681 | 16:27:56 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52165/tests/kraken/driver.html?testName=audio-dft) 16:27:57 INFO - PROCESS | 7681 | RSS: Main: 192700416 16:27:57 INFO - PROCESS | 7681 | 16:28:00 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52165/tests/kraken/driver.html?testName=audio-fft) 16:28:01 INFO - PROCESS | 7681 | RSS: Main: 271982592 16:28:01 INFO - PROCESS | 7681 | 16:28:03 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52165/tests/kraken/driver.html?testName=audio-oscillator) 16:28:04 INFO - PROCESS | 7681 | RSS: Main: 182362112 16:28:04 INFO - PROCESS | 7681 | 16:28:06 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52165/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:06 INFO - PROCESS | 7681 | RSS: Main: 186609664 16:28:06 INFO - PROCESS | 7681 | 16:28:10 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52165/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:10 INFO - PROCESS | 7681 | RSS: Main: 222945280 16:28:10 INFO - PROCESS | 7681 | 16:28:14 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52165/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:14 INFO - PROCESS | 7681 | RSS: Main: 216612864 16:28:14 INFO - PROCESS | 7681 | 16:28:17 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52165/tests/kraken/driver.html?testName=json-parse-financial) 16:28:18 INFO - PROCESS | 7681 | RSS: Main: 219951104 16:28:18 INFO - PROCESS | 7681 | 16:28:19 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52165/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:20 INFO - PROCESS | 7681 | RSS: Main: 185741312 16:28:20 INFO - PROCESS | 7681 | 16:28:22 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:23 INFO - PROCESS | 7681 | RSS: Main: 190836736 16:28:23 INFO - PROCESS | 7681 | 16:28:25 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:26 INFO - PROCESS | 7681 | RSS: Main: 195555328 16:28:26 INFO - PROCESS | 7681 | 16:28:28 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:29 INFO - PROCESS | 7681 | RSS: Main: 190222336 16:28:29 INFO - PROCESS | 7681 | 16:28:32 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:32 INFO - PROCESS | 7681 | RSS: Main: 194052096 16:28:32 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:52165/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52165/tests/kraken/driver.html?testName=ai-astar) 16:28:34 INFO - PROCESS | 7681 | RSS: Main: 243687424 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | __start_tp_report 16:28:34 INFO - PROCESS | 7681 | _x_x_mozilla_page_load 16:28:34 INFO - PROCESS | 7681 | _x_x_mozilla_page_load_details 16:28:34 INFO - PROCESS | 7681 | |i|pagename|runs| 16:28:34 INFO - PROCESS | 7681 | |0;ai-astar;104;113;114;115;115;114;113;113;115;114 16:28:34 INFO - PROCESS | 7681 | |1;audio-beat-detection;156;122;121;174;127;129;146;121;188;126 16:28:34 INFO - PROCESS | 7681 | |2;audio-dft;161;160;160;163;161;161;161;161;161;160 16:28:34 INFO - PROCESS | 7681 | |3;audio-fft;76;76;75;120;78;77;77;76;100;76 16:28:34 INFO - PROCESS | 7681 | |4;audio-oscillator;85;86;127;86;85;87;117;85;86;128 16:28:34 INFO - PROCESS | 7681 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;116;117;117 16:28:34 INFO - PROCESS | 7681 | |6;imaging-darkroom;146;147;146;146;147;147;146;146;146;147 16:28:34 INFO - PROCESS | 7681 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;95 16:28:34 INFO - PROCESS | 7681 | |8;json-parse-financial;63;63;62;62;61;61;69;61;62;68 16:28:34 INFO - PROCESS | 7681 | |9;json-stringify-tinderbox;66;66;67;65;66;66;68;65;69;65 16:28:34 INFO - PROCESS | 7681 | |10;stanford-crypto-aes;89;83;80;85;79;79;79;79;79;84 16:28:34 INFO - PROCESS | 7681 | |11;stanford-crypto-ccm;144;131;129;140;127;127;128;135;212;142 16:28:34 INFO - PROCESS | 7681 | |12;stanford-crypto-pbkdf2;169;165;163;167;163;163;164;168;167;164 16:28:34 INFO - PROCESS | 7681 | |13;stanford-crypto-sha256-iterative;70;68;75;66;68;66;68;67;70;66 16:28:34 INFO - PROCESS | 7681 | __end_tp_report 16:28:34 INFO - PROCESS | 7681 | __start_cc_report 16:28:34 INFO - PROCESS | 7681 | _x_x_mozilla_cycle_collect,1953 16:28:34 INFO - PROCESS | 7681 | __end_cc_report 16:28:34 INFO - PROCESS | 7681 | __startTimestamp1469748514877__endTimestamp 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | ------- Summary: start ------- 16:28:34 INFO - PROCESS | 7681 | Number of tests: 14 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.27 (2.9%) stddev-sans-first:0.87 16:28:34 INFO - PROCESS | 7681 | Values: 104.0 113.0 114.0 115.0 115.0 114.0 113.0 113.0 115.0 114.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:137.50 stddev:24.21 (17.6%) stddev-sans-first:25.06 16:28:34 INFO - PROCESS | 7681 | Values: 156.0 122.0 121.0 174.0 127.0 129.0 146.0 121.0 188.0 126.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 16:28:34 INFO - PROCESS | 7681 | Values: 161.0 160.0 160.0 163.0 161.0 161.0 161.0 161.0 161.0 160.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.00 stddev:14.96 (19.7%) stddev-sans-first:15.65 16:28:34 INFO - PROCESS | 7681 | Values: 76.0 76.0 75.0 120.0 78.0 77.0 77.0 76.0 100.0 76.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:85.50 stddev:18.72 (21.9%) stddev-sans-first:19.33 16:28:34 INFO - PROCESS | 7681 | Values: 85.0 86.0 127.0 86.0 85.0 87.0 117.0 85.0 86.0 128.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:28:34 INFO - PROCESS | 7681 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:28:34 INFO - PROCESS | 7681 | Values: 146.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 146.0 147.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:28:34 INFO - PROCESS | 7681 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:62.50 stddev:2.90 (4.6%) stddev-sans-first:3.07 16:28:34 INFO - PROCESS | 7681 | Values: 63.0 63.0 62.0 62.0 61.0 61.0 69.0 61.0 62.0 68.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:1.34 (2.0%) stddev-sans-first:1.41 16:28:34 INFO - PROCESS | 7681 | Values: 66.0 66.0 67.0 65.0 66.0 66.0 68.0 65.0 69.0 65.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.50 stddev:3.50 (4.3%) stddev-sans-first:2.49 16:28:34 INFO - PROCESS | 7681 | Values: 89.0 83.0 80.0 85.0 79.0 79.0 79.0 79.0 79.0 84.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:137.50 stddev:25.58 (18.6%) stddev-sans-first:27.12 16:28:34 INFO - PROCESS | 7681 | Values: 144.0 131.0 129.0 140.0 127.0 127.0 128.0 135.0 212.0 142.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.00 stddev:2.26 (1.4%) stddev-sans-first:1.96 16:28:34 INFO - PROCESS | 7681 | Values: 169.0 165.0 163.0 167.0 163.0 163.0 164.0 168.0 167.0 164.0 16:28:34 INFO - PROCESS | 7681 | 16:28:34 INFO - PROCESS | 7681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:2.76 (4.1%) stddev-sans-first:2.86 16:28:34 INFO - PROCESS | 7681 | Values: 70.0 68.0 75.0 66.0 68.0 66.0 68.0 67.0 70.0 66.0 16:28:34 INFO - PROCESS | 7681 | -------- Summary: end -------- 16:28:34 INFO - PROCESS | 7681 | 16:28:36 INFO - TEST-INFO | 7681: exit 0 16:28:36 INFO - TEST-OK | kraken | took 64322ms 16:28:36 INFO - SUITE-END | took 1184s 16:28:36 INFO - Completed test suite (00:19:45) 16:28:36 INFO - javascript benchmark 16:28:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2625.776808971237, 2630.9605355919666, 2652.801039922005, 2667.253169112648, 2674.893004279829, 4696.154692155411, 4723.976380118103, 4724.5662509645745, 4836.081144582527, 4837.080954618625, 4737.573618374346, 4788.64085193611, 4802.327674125625, 4802.471728109905, 4836.951630483694, 4347.978260108695, 4355.36847157162, 4451.2655411857, 4512.255477846152, 4568.132054909556, 4475.641948644101, 4504.729716217021, 4505.43682039745, 4531.297648864414, 4531.932021019687, 4265.829366825324, 4339.913201735948, 4340.0, 4369.038811461473, 4461.486929003174, 4345.869623911288, 4348.977990172307, 4356.782160891975, 4406.911861762779, 4406.955930440687, 4314.115606300732, 4391.748351719744, 4396.472423309191, 4431.667624928111, 4435.223835828717, 4623.56076172763, 4772.188727916263, 4825.059113472874, 4877.439094504142, 4885.071836351118, 5093.286939828427, 5140.33175687162, 5154.4587818278915, 5212.348456442945, 5245.134552798783, 5191.2472691459525, 5195.844124676266, 5227.660202086852, 5231.633785635007, 5245.396779370345, 4698.248280275208, 4793.448753393334, 4838.153323168467, 4887.535684110004, 4893.388326459192, 5269.631125821155, 5308.654937429054, 5363.0, 5366.034113859543, 5394.514493695587, 5139.511746384088, 5153.2785410042625, 5154.9484505155215, 5175.17197248446, 5177.715225662625, 4988.451270360258, 5094.5160209780015, 5271.630985830955, 5278.577713782888, 5282.947170528246, 5048.5203905628905, 5077.339945807022, 5087.084324821493, 5128.3076784633595, 5131.846044618593, 5052.368453943257, 5059.316992206079, 5066.518680725325, 5107.055194788902, 5112.769925353368, 4259.787010649455, 4281.443412356436, 4360.803763830635, 4371.650267978618, 4404.493483249467, 3781.640744129303, 3792.089898424358, 3825.8087095645665, 3871.032241939515, 3888.0862997195636, 4415.823367065345, 4418.270985287456, 4435.090806384684, 4454.28731402974, 4482.21561226784, 5003.449620541737, 5176.275321454924, 5185.507376799198, 5192.636515443883, 5243.056249875059, 4299.591538803808, 4311.892202694895, 4325.6106950374615, 4354.0, 4367.803448844809, 4545.38637283969, 4547.431571053618, 4561.498235194126, 4582.587567118975, 4591.265397536378, 4712.363830882787, 4749.4063242094735, 4754.405699287589, 4757.452892917289, 4807.278908163804, 4979.9004019918675, 5034.345535080416, 5073.6702114363925, 5080.872978175576, 5086.287919691172, 4831.661783675109, 4843.104025755247, 4845.781939812788, 4865.70805751656, 4874.8537543873745, 5026.9694712583105, 5071.93489367237, 5079.87300317495, 5080.593552515864, 5105.2086926526445, 5042.899142017212, 5118.667286626357, 5159.122949098737, 5182.6113041521285, 5189.76646050921, 5093.261477085876, 5094.770735316845, 5096.46487118862, 5116.692998420107, 5124.0, 5095.745212739423, 5109.489051095009, 5110.770015349392, 5114.2840002400435, 5159.896802063862, 4836.758162091952, 4898.559129678346, 4946.678465899705, 5032.798688052583, 5045.091883461012, 5055.0, 5057.6459647824295, 5061.620378471555, 5083.491650834887, 5115.718635475085, 4899.31409602663, 4916.311716359784, 4923.384576927884, 4927.679700819579, 4964.950350496449], "value": 4719.632271196423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.3309044962617, 575.7006356694519, 585.2245774348988, 587.0284678856491, 590.3299754778325, 26979.730202697967, 39002.41496377557, 40408.19183616329, 40447.595524044824, 44886.32670509945, 40121.38604060526, 40668.59331406692, 40709.79645101778, 40760.38859417111, 40877.0, 7722.691092356299, 7819.804504887395, 7826.178251283619, 7826.491278066936, 7863.095743989428, 7325.12098548175, 9770.218382529381, 9860.802783944317, 9891.109800117987, 10313.89686103137, 10184.847227291597, 10268.794624107513, 10278.948605256963, 10283.485825708685, 10284.691459256233, 10083.495825208747, 10148.695539133838, 10149.0865822076, 10152.39085654863, 10156.0, 9976.401415914996, 10051.0, 10055.245856560787, 10056.748581285454, 10062.144717699004, 38452.80773596142, 38829.22341553153, 38854.41718374227, 38903.0, 38939.22121557581, 3109.5957525521762, 3138.7489000879873, 3159.636641786201, 3270.7546933980047, 3460.9963110697868, 27821.887124514997, 28430.857845710845, 28535.429291414264, 28541.857290713622, 28575.9998400055, 3377.9493307600405, 3379.3158124231913, 3467.2025434150287, 3593.3531964246426, 3594.856205751767, 237142.0, 238444.0, 239139.0, 239669.80165099236, 240892.0, 28887.277818054717, 52659.73670131625, 52948.735256324246, 53019.46980530145, 53062.734686327094, 30979.845100774804, 32776.180595485304, 32887.34225315528, 32929.835350823094, 32954.3409131816, 13546.661333466742, 13710.17738935667, 13711.588652340446, 13716.0, 13816.0, 10697.679069627768, 11510.482028308747, 11739.06087512997, 11799.88200118005, 11819.349935753444, 7625.084989801259, 7678.57767822764, 7821.960890195513, 7831.334336581454, 7850.921490785019, 3045.33002739397, 3123.2035830862983, 3125.7499400048396, 3138.5919830422354, 3144.638366587826, 808.2523665609287, 884.9646014159362, 956.039180623469, 958.8657587937695, 989.4360214677708, 26237.0, 26721.732782672312, 26855.194344169708, 27129.86435067812, 27245.318867028087, 938.4979036215728, 943.6084025129632, 955.9569819358006, 1001.1090129784498, 1082.3938594387012, 13885.166889986633, 14391.78412323816, 14496.420143194155, 14768.63078423048, 14820.3330850112, 314771.0, 320678.0, 321030.0, 321884.0, 321899.0, 46505.30242046372, 46651.0, 46657.76671116623, 47091.0, 47127.0, 1051.358671210561, 1140.3386036099043, 1152.8501294831785, 1180.477184432073, 1194.6714653470192, 27624.447511049264, 29241.0, 29300.0, 29336.119916402542, 29812.552811708276, 42112.789436052626, 42518.36222456604, 42636.0, 42662.0, 42682.1463570733, 299039.0, 302640.0, 302849.0, 302924.0, 303036.0, 25246.621300680137, 25723.871380642977, 25821.483570328863, 26075.217743467725, 27326.316842079108, 40685.59314406818, 40859.59140408558, 41071.0, 41433.37849932314, 42195.57804421917, 35580.64419355773, 35671.0, 35731.0, 36422.0, 36457.45313820348, 35666.821665891504, 35814.28371432609, 35898.0, 36402.817985909904, 36579.0, 35298.29403411969, 35467.645323546436, 35559.288814224084, 35610.82194589011, 35851.82074089613, 31017.0694879154, 32363.838180808943, 32597.837010814794, 32716.509252360756, 32813.0, 24906.377340566632, 24984.75015249897, 25161.74838251594, 25171.0, 25544.616830747924], "value": 16734.310969322654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.1980186166233, 363.2843298701557, 368.9483472313876, 372.099519163624, 388.3572687202679, 13039.152455090425, 16262.186890655465, 16502.834971650296, 16585.336586536523, 17612.911935440305, 16579.83420165798, 16775.91612041941, 16849.494515164533, 16947.57631059224, 17111.40110096147, 4941.283513890484, 5092.745362731867, 5129.563987061104, 5137.049645815518, 5181.5077567631015, 3751.5685696144883, 5089.440161582223, 5174.560162386183, 5738.827835164931, 6049.0623953287095, 6061.696915154247, 6086.417531441835, 6094.329623741407, 6104.023356262999, 6111.0, 5986.221791167142, 5987.64074155552, 6042.9395706042815, 6075.301340345828, 6157.969210153965, 5196.558292545138, 5391.16436952273, 5394.433584473613, 5394.811181608664, 5396.946030539723, 20421.387358379125, 20594.27920022792, 20602.484937876525, 20713.482162946046, 20714.171433142656, 1841.8250266224686, 1859.349227770283, 1889.5842914558912, 1908.4942490240098, 2026.6453370660076, 12618.873811261861, 13223.801642975362, 13572.0, 13596.32018399086, 13826.861731382658, 1711.2727090986282, 1790.543411430077, 1806.6477036977794, 1841.5212044868297, 1847.7782666080022, 68399.65800170967, 69262.0, 69291.99999999949, 70656.6467167666, 71380.0, 9531.952340238255, 9574.664886728931, 9788.363756355815, 9977.700668979942, 10371.481425928674, 13416.731665366638, 13438.529651462151, 13445.865541344658, 13476.39356228972, 13484.865151348557, 7367.226441223702, 7397.26027397256, 7544.811379715551, 7582.696692132363, 7632.618369081523, 7080.6105664187935, 7093.184283807428, 7097.396721278646, 7315.817104572428, 7326.0, 6010.759569617167, 6147.877042459215, 6174.475169610543, 6186.980800726217, 6281.120643109968, 1737.261663792883, 1789.579448829524, 1794.6231291428946, 1813.3925135079896, 1819.8908065516111, 805.3235282362843, 880.0495464897895, 911.1480765484293, 966.0387914025449, 970.427447805798, 16645.33418663264, 16834.0, 17448.12759362027, 17811.287548498174, 17863.285468581373, 798.7883210949103, 846.4328899637258, 861.2076889261895, 867.8828358171565, 927.8979312275644, 9508.857367139499, 9612.903870961341, 9761.9023809761, 10159.796804063877, 10397.948010259901, 392357.0, 394656.0, 395439.0, 397863.0106849505, 400592.0, 78307.0, 78488.0, 79662.00844978806, 81011.0, 82293.58853205695, 892.4466830565091, 904.5477261369184, 932.8973812880578, 936.6908920056366, 941.0072373645733, 15225.77161342603, 15842.52472425829, 16376.918115409348, 16423.0, 16615.252313646157, 28104.29739256535, 29654.851725741235, 35850.92447226588, 38184.0, 39225.6077439222, 51500.742496287276, 52191.0, 52310.0, 52380.47619523756, 52482.0, 7044.401225895856, 7450.0, 7467.6639551221415, 7484.363829074589, 7871.921280787119, 8643.0, 12060.698482538006, 12206.389680516117, 12307.507699691912, 12479.376031198222, 10288.459157649857, 10344.27590068688, 10435.165186784887, 10562.31344962575, 10563.154947604327, 6156.753729850756, 10466.790664186827, 10742.355458672504, 10807.243492955418, 10824.458777061272, 10374.533146008598, 10455.84316235242, 10503.632372866912, 10519.105876000625, 10891.455427228766, 7668.923310766821, 7749.457537972288, 7767.53394796314, 7822.882656760267, 7824.178461261486, 9255.444673319622, 10168.593256269962, 10427.478626068818, 10719.356838589709, 10885.673429797118], "value": 8440.672509370177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.5657948007934, 1344.2472215559285, 1390.3117956611475, 1452.4698485052959, 1696.3638635511675, 6773.559718618292, 7475.102987641484, 7727.884081738778, 7735.651895664701, 7956.880646790303, 7774.144844067148, 7777.0, 7820.687172513107, 7833.647485863135, 7842.72550460734, 2137.1451419432233, 2161.329987703809, 2161.6541353383464, 2163.491579478821, 2165.1880544795704, 2126.192047022137, 2164.0, 2206.106526856626, 2211.469247380625, 2215.0253888289103, 2140.56118495708, 2147.24846303794, 2157.805797478227, 2158.9568208635897, 2182.3998400439846, 2128.8190503807195, 2135.9786402136015, 2136.6794980752857, 2138.0, 2138.994672503921, 2079.105984426696, 2125.160561578183, 2130.4886827161527, 2131.520407908208, 2151.2363111095647, 7706.422018348646, 7789.688412463494, 7866.488678235954, 7867.0952840423515, 7875.015623047119, 4673.065386922629, 4768.094062128219, 4769.236922092483, 4770.904581908377, 4772.832950846704, 2843.445528122017, 2903.5789810477363, 3017.0, 3017.4719424100913, 3017.8189308641554, 867.2758246863813, 872.2062922740339, 872.3370238618633, 880.5949263338872, 885.5749240364597, 10891.564337426493, 10974.451277436096, 11000.394978276192, 11000.559977600886, 11011.339319640847, 3953.7825419601622, 4135.31767258399, 4235.301175306102, 4290.141971605692, 4292.248856450109, 8585.31317494599, 8594.0, 8594.699185528476, 8595.312375009984, 8597.785055373666, 4650.37219975299, 4698.8355407560575, 4700.130475861976, 4702.506236845151, 4703.0123674028155, 3693.7414380993073, 4190.790460476964, 4334.046509767846, 4379.540148284448, 4383.320585309282, 3349.112485191426, 3362.402858642174, 3474.635163307816, 3482.094655389617, 3505.544279243733, 1002.1682003936716, 1010.0707349238718, 1010.6563768318659, 1027.8817935937464, 1035.9689209323733, 845.2941793602411, 852.4416507187802, 859.8108416148438, 884.6151923913119, 903.7559858838208, 5377.7311134443125, 5504.697241651747, 5641.041023026095, 5655.830325090254, 5730.19777231188, 814.5112932240609, 832.9958350208332, 865.446114486729, 883.1168831168832, 927.8608208768738, 3614.783113013175, 3696.796676182782, 3739.2521495700967, 3837.520309961255, 3839.347310957144, 9741.464219467856, 9987.650432234837, 10083.697489075339, 10089.445080520494, 10145.08694217524, 9980.900190998142, 10015.39907605546, 10033.347832390871, 10034.0, 10049.397036177852, 7305.0, 7399.593022383713, 7411.2959268870445, 7413.740519081806, 7418.480706350504, 5286.074936886106, 5373.946260537345, 5535.25274087993, 5568.387477377566, 5683.204351390891, 9090.863637045317, 9096.590653420479, 9126.224270936778, 9169.633214671338, 9250.95374523123, 9076.0, 9288.442693438416, 9340.0, 9445.338826282368, 9491.240700744062, 6435.2599451063725, 6594.8351291218105, 6633.436157926437, 6660.766873159416, 6809.182898052265, 7635.121960974559, 7704.537727736129, 7707.036620422447, 7709.807254818675, 7711.614419279126, 6821.079154314302, 6892.862142757217, 6898.827529311808, 6940.895886561605, 7062.223155452999, 6671.466282697275, 6744.426723728342, 6757.7634782784335, 6801.081853949653, 6822.317768223138, 6534.444572211415, 6559.409653131241, 6602.372774586406, 6617.338266173344, 6619.371159739817, 4592.15045216636, 4673.696209746356, 4726.432828060655, 4815.109204797123, 4915.26271059344, 4663.790129444115, 4695.154872123051, 4839.854804355875, 4857.975710121426, 4912.238603016538], "value": 4162.541345705945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.33741731435003, 293.8016838633922, 302.6625312776254, 303.80404639007827, 304.26216425168957, 4972.2790195421685, 5006.524380183877, 5007.824726134581, 5015.4984501549825, 5017.799288028475, 4692.724028846007, 4802.255650374189, 4851.878703032426, 4866.343043689101, 4932.753362331878, 3752.1182522107283, 3789.5831458539537, 3803.8478460861534, 3839.001859516532, 3881.611838816124, 3894.6689531389725, 3896.688264938798, 3902.2585708715396, 3915.921681566367, 3928.430377595247, 3615.7830530168144, 3784.8107594620296, 3871.5160604924386, 3888.358420860569, 3898.8245528951265, 3799.8670046548377, 3802.923941521182, 3812.9618703812885, 3821.464994900716, 3829.5404551454003, 3272.705456508902, 3318.3197444523817, 3322.0864262327814, 3329.1344250494803, 3330.417176994041, 4826.831060912851, 4849.9757501212625, 4862.149123903303, 4862.975685121587, 4875.439324477659, 4153.92303848076, 4341.717788343779, 4349.608535231812, 4353.36876152956, 4361.127774445092, 5148.3564554430695, 5172.27588137661, 5189.299444574972, 5198.636095473318, 5212.29633999409, 3651.963480365189, 3665.7617254878614, 3666.9633303666888, 3674.430463278196, 3681.723870709681, 5168.198929165986, 5371.060064488699, 5394.811181608625, 5447.4824891635235, 5468.91796623051, 3697.5378077740284, 3704.5369328833895, 3715.275521273326, 3722.1575320216666, 3728.179800443898, 3752.75607085544, 3754.9436758448646, 3759.7556158850134, 3764.4189440435084, 3767.8334957399607, 4240.703150779477, 4313.158934007901, 4328.653707703439, 4329.242382583098, 4352.325389564622, 5125.1287281162295, 5147.3051138096625, 5166.483351664803, 5169.0, 5196.402413722471, 1281.628327784932, 1300.024981264052, 1303.1985329022773, 1304.504288370432, 1308.5354699081688, 815.5881279953585, 865.4288169807896, 878.8004374029532, 891.1133422244778, 896.3859756067114, 4515.14212299662, 4526.6152377047665, 4528.139653465897, 4534.206513860061, 4543.909121817545, 5238.345206849144, 5240.9213861792105, 5253.343332083489, 5314.990151871132, 5320.095583750772, 4291.6137547620865, 4455.487618923865, 4475.440569928759, 4487.237169681097, 4489.842855500042, 2772.6672799263815, 2786.275568352243, 2791.9022834200705, 2796.804223704321, 2801.173653772142, 4925.586462633284, 4947.678400903929, 4950.3069570260195, 4967.832559348551, 4971.37857767779, 5253.425821347589, 5298.682079075268, 5324.653897496651, 5337.2527846101575, 5364.015314333721, 4932.630052745986, 4932.827351042696, 4952.158133117378, 4956.157453232959, 4961.709955411691, 5252.448492908343, 5256.894862102813, 5263.921041184309, 5271.341082364705, 5277.498637629419, 4907.877303067452, 5045.7981680732355, 5111.974440127924, 5117.74411279442, 5156.1492353761205, 4942.182955715208, 4945.530174633404, 4949.950500494949, 4958.380202474691, 4963.602911767122, 3719.20037192005, 3723.9813800930824, 3740.7194460415035, 3748.981255093707, 3751.437284407361, 5053.317802096817, 5211.348581427322, 5287.589504769326, 5295.391030031442, 5308.283381743415, 5166.682495963505, 5177.326947496952, 5197.882455272134, 5221.373435187802, 5223.503767142115, 5230.529252367305, 5252.135491900473, 5254.710990895537, 5263.578913686973, 5276.366835979707, 4852.0, 4888.706677599355, 5095.668781529189, 5126.077306084941, 5130.179171332718, 4983.8504844854715, 5249.7112658804135, 5255.816046438356, 5286.0, 5298.602604804732, 5069.88462538241, 5102.821401250939, 5107.744612769272, 5123.692578445305, 5136.563392111712], "value": 3868.603427152279, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.9046552551591, 633.8288662061241, 648.5816648261871, 666.0841343153164, 670.2560158224371, 11473.311601303927, 12022.57920972766, 12083.154179207459, 12140.514379424823, 12183.147179697413, 12283.44724487398, 12450.128491005633, 12458.937705311484, 12463.750724985495, 12486.750264994695, 619.9163112979735, 739.8742213823635, 753.3333000294751, 771.6450432800918, 775.7246177606953, 756.3382040714375, 758.8709919313729, 759.5784339691469, 759.6125975752378, 760.0309605253291, 808.6320724070533, 810.0198759501011, 810.0239211749828, 810.7486679129489, 811.419834818105, 652.161971866152, 668.6422763821381, 670.3631550027445, 674.1741366825648, 816.1145157504116, 570.57207094679, 589.0310439327326, 589.7581991383512, 591.2668291318734, 592.052715654953, 11874.940625296904, 11891.405429728566, 11952.402379881058, 11987.64037078889, 11988.940055299669, 11127.332360058423, 11385.772284554345, 11427.885721142848, 11582.0, 12257.877421225852, 10249.948750256275, 10274.075333220046, 10321.84517232242, 10373.585056597727, 10470.319429237075, 3903.512060992376, 3996.0609256824623, 3999.200159968018, 4015.4779878615886, 4085.101277718897, 57135.42864571343, 57202.42797572013, 57489.13766293467, 57642.42357576412, 58419.494830310316, 9257.981622021573, 9259.629614815467, 9277.721668349961, 9280.953595232115, 9297.581608827619, 16943.661126777395, 17055.658886822195, 17069.7439538407, 17171.828281717273, 17230.569235768955, 6579.638119903356, 6641.966790166019, 6663.03386009026, 6708.932910670928, 6760.0, 3520.2431477232517, 3585.6414358563934, 3590.1922067534597, 3595.8921232363073, 3604.13500759821, 2524.684414448194, 2594.8702564871683, 2607.361196506868, 2608.595667671514, 2617.9214623560943, 477.3388856433864, 480.9158397280462, 482.0383335246136, 493.84937594034164, 499.37328652541555, 446.13004640950277, 468.1409613359455, 509.6967304453844, 536.7584586935894, 539.2746755613693, 6494.577852439576, 6529.967350163314, 6616.139901812733, 6634.767783127664, 6747.0, 494.88617617948074, 513.2711549599578, 514.9253358263031, 518.0959226150392, 520.0275484843368, 3178.8251646159683, 3188.87244510217, 3208.374366998413, 3229.531717900938, 3263.640999490054, 76254.61872690602, 93614.0, 99517.0, 100248.49875750575, 100322.4983875076, 47959.0, 78581.0, 78592.0, 78614.0, 79129.60435197788, 49971.50028499741, 67723.0, 74025.25974740292, 74731.0, 74735.0, 7481.925180748123, 8091.676332946617, 8195.262426381654, 8228.42401031922, 8350.498970061815, 44819.32771008372, 45598.31602525898, 45695.77152114218, 46412.76793616146, 46621.53378466172, 59038.40961590501, 61950.38049619627, 62522.0, 63516.68241658947, 64099.11553980175, 10299.845502317621, 10465.581376744845, 10645.254832161674, 10773.19201059908, 10813.189010824377, 11437.142214334124, 11548.538058477567, 11667.00830429423, 11883.881161188623, 12042.518299268282, 10289.84565231507, 10605.8939410608, 10701.946490267499, 10906.072983796148, 11057.668269951915, 9841.940040026631, 10172.0, 10542.947285263524, 10902.454877255946, 11013.504392302526, 10283.382997020202, 10545.367277963347, 10717.981791729773, 10918.399488028235, 10933.890661093288, 8826.382153249211, 8839.425437346552, 9026.277897768294, 9140.360174787702, 9386.530673466435, 9367.110124538158, 9693.369930954465, 9789.755256118655, 9858.0, 9982.700518984442], "value": 6122.465523646449, "unit": "score"}], "name": "dromaeo_css", "value": 6352.770333527153, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 114.0, 115.0, 115.0, 114.0, 113.0, 113.0, 115.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 121.0, 174.0, 127.0, 129.0, 146.0, 121.0, 188.0, 126.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 163.0, 161.0, 161.0, 161.0, 161.0, 161.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 120.0, 78.0, 77.0, 77.0, 76.0, 100.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 127.0, 86.0, 85.0, 87.0, 117.0, 85.0, 86.0, 128.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 61.0, 61.0, 69.0, 61.0, 62.0, 68.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 68.0, 65.0, 69.0, 65.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 80.0, 85.0, 79.0, 79.0, 79.0, 79.0, 79.0, 84.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 131.0, 129.0, 140.0, 127.0, 127.0, 128.0, 135.0, 212.0, 142.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 163.0, 167.0, 163.0, 163.0, 164.0, 168.0, 167.0, 164.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 75.0, 66.0, 68.0, 66.0, 68.0, 67.0, 70.0, 66.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1540.0, "alertThreshold": 2.0}]} 16:28:36 INFO - Return code: 0 16:28:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:36 INFO - # TBPL SUCCESS # 16:28:36 INFO - Running post-action listener: _resource_record_post_action 16:28:36 INFO - [mozharness: 2016-07-28 23:28:36.674434Z] Finished run-tests step (success) 16:28:36 INFO - Running post-run listener: _resource_record_post_run 16:28:36 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 556621824; Read time: 12; Write time: 1270740 16:28:36 INFO - TinderboxPrint: CPU usage
13.1% 16:28:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:28:36 INFO - TinderboxPrint: I/O write bytes / time
556,621,824 / 1,270,740 16:28:36 INFO - TinderboxPrint: CPU idle
8,345.5 (86.9%) 16:28:36 INFO - TinderboxPrint: CPU user
1,199.2 (12.5%) 16:28:36 INFO - TinderboxPrint: Swap in / out
0 / 0 16:28:36 INFO - install - Wall time: 18s; CPU: 19.0%; Read bytes: 0; Write bytes: 257916928; Read time: 0; Write time: 834968 16:28:36 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 298090496; Read time: 0; Write time: 435772 16:28:37 INFO - Running post-run listener: _upload_blobber_files 16:28:37 INFO - Blob upload gear active. 16:28:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:28:37 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'] 16:28:37 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 16:28:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:41 INFO - (blobuploader) - INFO - Done attempting. 16:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:28:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:42 INFO - (blobuploader) - INFO - Done attempting. 16:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:42 INFO - (blobuploader) - INFO - Done attempting. 16:28:42 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:42 INFO - Return code: 0 16:28:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7af440ef08fd7c97e501cbcf26f2ba45001e3b1dcd6f096383ce6dad36e4874543e6ed425fca92cb2df4e38a7466017efc3c75e318ff6914ac982f53702d9a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2397b44dccdc5ddf7d905f1a240d52b388c920aec0c7c3f4cea7d617bdc22e0e2a6111cd9778206f948f41a7e037aace5c0a9c92d4907b66dcbfb2b15c12198e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43662a50e2ebe11e0345f890fe05240f1a3795727cd67fa2fb32d5aead41c541e281298a5edbc60043ca0a6f0b53c20b443af84ef411d42fc6820ce7c1b18c53"} 16:28:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:42 INFO - Contents: 16:28:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7af440ef08fd7c97e501cbcf26f2ba45001e3b1dcd6f096383ce6dad36e4874543e6ed425fca92cb2df4e38a7466017efc3c75e318ff6914ac982f53702d9a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2397b44dccdc5ddf7d905f1a240d52b388c920aec0c7c3f4cea7d617bdc22e0e2a6111cd9778206f948f41a7e037aace5c0a9c92d4907b66dcbfb2b15c12198e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43662a50e2ebe11e0345f890fe05240f1a3795727cd67fa2fb32d5aead41c541e281298a5edbc60043ca0a6f0b53c20b443af84ef411d42fc6820ce7c1b18c53"} 16:28:42 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:42 INFO - Copying logs to upload dir... 16:28:42 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.374415 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-28 16:28:42.782102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 16:28:42.785784) ========= 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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 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/7af440ef08fd7c97e501cbcf26f2ba45001e3b1dcd6f096383ce6dad36e4874543e6ed425fca92cb2df4e38a7466017efc3c75e318ff6914ac982f53702d9a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2397b44dccdc5ddf7d905f1a240d52b388c920aec0c7c3f4cea7d617bdc22e0e2a6111cd9778206f948f41a7e037aace5c0a9c92d4907b66dcbfb2b15c12198e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43662a50e2ebe11e0345f890fe05240f1a3795727cd67fa2fb32d5aead41c541e281298a5edbc60043ca0a6f0b53c20b443af84ef411d42fc6820ce7c1b18c53"} build_url:https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/WS0u8bH4RR-BHQ5aNZ3IMA/artifacts/public/build/firefox-50.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/7af440ef08fd7c97e501cbcf26f2ba45001e3b1dcd6f096383ce6dad36e4874543e6ed425fca92cb2df4e38a7466017efc3c75e318ff6914ac982f53702d9a33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2397b44dccdc5ddf7d905f1a240d52b388c920aec0c7c3f4cea7d617bdc22e0e2a6111cd9778206f948f41a7e037aace5c0a9c92d4907b66dcbfb2b15c12198e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/43662a50e2ebe11e0345f890fe05240f1a3795727cd67fa2fb32d5aead41c541e281298a5edbc60043ca0a6f0b53c20b443af84ef411d42fc6820ce7c1b18c53"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 16:28:42.811146) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:28:42.811486) ========= 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=745a4373a2a16df560f216e000000264-1469747155.93915-559281507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 16:28:42.894374) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 16:28:42.894685) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 16:28:42.894972) ========= ========= Total master_lag: 0.17 =========