builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1470090809.4 results: success (0) buildid: 20160801140551 builduid: 17d661177896404a9347f6bfddfbf3c2 revision: b2a6aaf59783cecf8cde63fe51fbd65e14b026df ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.405327) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.405799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.406112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.421426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.421726) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 15:33:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.4M=0s 2016-08-01 15:33:29 (37.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.600489) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.601363) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016502 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.654891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:29.655313) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b2a6aaf59783cecf8cde63fe51fbd65e14b026df --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 b2a6aaf59783cecf8cde63fe51fbd65e14b026df --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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 15:33:29,704 truncating revision to first 12 chars 2016-08-01 15:33:29,704 Setting DEBUG logging. 2016-08-01 15:33:29,704 attempt 1/10 2016-08-01 15:33:29,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b2a6aaf59783?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 15:33:30,014 unpacking tar archive at: autoland-b2a6aaf59783/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:30.317500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:30.318292) ========= script_repo_revision: b2a6aaf59783cecf8cde63fe51fbd65e14b026df ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:30.319278) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:30.319959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 15:33:30.334118) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-01 15:33:30.334816) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:33:30 INFO - MultiFileLogger online at 20160801 15:33:30 in /builds/slave/test 15:33:30 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 15:33:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:30 INFO - {'append_to_log': False, 15:33:30 INFO - 'base_work_dir': '/builds/slave/test', 15:33:30 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:33:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:30 INFO - 'branch': 'Autoland-Non-PGO', 15:33:30 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:30 INFO - 'config_files': ('talos/linux_config.py',), 15:33:30 INFO - 'default_actions': ('clobber', 15:33:30 INFO - 'read-buildbot-config', 15:33:30 INFO - 'download-and-extract', 15:33:30 INFO - 'populate-webroot', 15:33:30 INFO - 'create-virtualenv', 15:33:30 INFO - 'install', 15:33:30 INFO - 'run-tests'), 15:33:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:30 INFO - 'download_minidump_stackwalk': True, 15:33:30 INFO - 'download_symbols': 'ondemand', 15:33:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:30 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:30 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:30 INFO - 'installer_path': 'installer.exe', 15:33:30 INFO - 'log_level': 'info', 15:33:30 INFO - 'log_name': 'talos', 15:33:30 INFO - 'log_to_console': True, 15:33:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:33:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:33:30 INFO - 'opt_config_files': (), 15:33:30 INFO - 'pip_index': False, 15:33:30 INFO - 'sps_profile': False, 15:33:30 INFO - 'sps_profile_interval': 0, 15:33:30 INFO - 'suite': 'dromaeojs', 15:33:30 INFO - 'system_bits': '32', 15:33:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:30 INFO - 'title': 'talos-linux64-ix-013', 15:33:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:30 INFO - 'use_talos_json': True, 15:33:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:30 INFO - 'work_dir': 'build'} 15:33:30 INFO - [mozharness: 2016-08-01 22:33:30.474710Z] Running clobber step. 15:33:30 INFO - Running pre-action listener: _resource_record_pre_action 15:33:30 INFO - Running main action method: clobber 15:33:30 INFO - rmtree: /builds/slave/test/build 15:33:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:30 INFO - Running post-action listener: _resource_record_post_action 15:33:30 INFO - [mozharness: 2016-08-01 22:33:30.915330Z] Finished clobber step (success) 15:33:30 INFO - [mozharness: 2016-08-01 22:33:30.915431Z] Running read-buildbot-config step. 15:33:30 INFO - Running pre-action listener: _resource_record_pre_action 15:33:30 INFO - Running main action method: read_buildbot_config 15:33:30 INFO - Using buildbot properties: 15:33:30 INFO - { 15:33:30 INFO - "project": "", 15:33:30 INFO - "product": "firefox", 15:33:30 INFO - "script_repo_revision": "production", 15:33:30 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:33:30 INFO - "repository": "", 15:33:30 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:33:30 INFO - "buildid": "20160801140551", 15:33:30 INFO - "pgo_build": "False", 15:33:30 INFO - "basedir": "/builds/slave/test", 15:33:30 INFO - "buildnumber": 118, 15:33:30 INFO - "slavename": "talos-linux64-ix-013", 15:33:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:33:30 INFO - "platform": "ubuntu64_hw", 15:33:30 INFO - "scheduler": "tests-autoland-linux64-talos", 15:33:30 INFO - "branch": "autoland", 15:33:30 INFO - "repo_path": "integration/autoland", 15:33:30 INFO - "revision": "b2a6aaf59783cecf8cde63fe51fbd65e14b026df", 15:33:30 INFO - "stage_platform": "linux64", 15:33:30 INFO - "builduid": "17d661177896404a9347f6bfddfbf3c2", 15:33:30 INFO - "slavebuilddir": "test" 15:33:30 INFO - } 15:33:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:33:30 INFO - Running post-action listener: _resource_record_post_action 15:33:30 INFO - [mozharness: 2016-08-01 22:33:30.917904Z] Finished read-buildbot-config step (success) 15:33:30 INFO - [mozharness: 2016-08-01 22:33:30.917993Z] Running download-and-extract step. 15:33:30 INFO - Running pre-action listener: _resource_record_pre_action 15:33:30 INFO - Running main action method: download_and_extract 15:33:30 INFO - mkdir: /builds/slave/test/build/tests 15:33:30 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')]} 15:33:30 INFO - https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:33:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:33:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:33:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:31 INFO - Downloaded 1361 bytes. 15:33:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:33:31 INFO - Using the following test package requirements: 15:33:31 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:33:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:33:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:33:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'jsshell-linux-x86_64.zip'], 15:33:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:33:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:33:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:33:31 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:33:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:33:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:33:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:33:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:33:31 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')]} 15:33:31 INFO - https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:33:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:33:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:33:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:32 INFO - Downloaded 20237696 bytes. 15:33:32 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'] 15:33:32 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 15:33:33 INFO - Return code: 0 15:33:33 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 15:33:33 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')]} 15:33:33 INFO - https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:33:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:33:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:33:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:34 INFO - Downloaded 20237696 bytes. 15:33:34 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'] 15:33:34 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 15:33:35 INFO - Return code: 0 15:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:35 INFO - https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:33:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:33:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:33:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:37 INFO - Downloaded 14413723 bytes. 15:33:37 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'] 15:33:37 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 15:33:37 INFO - Return code: 0 15:33:37 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')]} 15:33:37 INFO - https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:33:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:33:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:33:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRhkElOBSQeihP_JSsSw2A/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 15:33:38 INFO - Downloaded 55676766 bytes. 15:33:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:33:38 INFO - mkdir: /builds/slave/test/properties 15:33:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:33:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:33:38 INFO - Contents: 15:33:38 INFO - build_url:https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:33:39 INFO - Running post-action listener: _resource_record_post_action 15:33:39 INFO - Running post-action listener: set_extra_try_arguments 15:33:39 INFO - [mozharness: 2016-08-01 22:33:39.617661Z] Finished download-and-extract step (success) 15:33:39 INFO - [mozharness: 2016-08-01 22:33:39.617894Z] Running populate-webroot step. 15:33:39 INFO - Running pre-action listener: _resource_record_pre_action 15:33:39 INFO - Running main action method: populate_webroot 15:33:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:33:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:33:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:33:39 INFO - '--tppagecycles', 15:33:39 INFO - '7'], 15:33:39 INFO - 'tests': ['tsvgm']}}, 15:33:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:33:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:33:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:33:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:33:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:33:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:33:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['damp', 'tps']}, 15:33:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'tests': ['damp', 'tps']}, 15:33:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['dromaeo_dom']}, 15:33:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:33:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['basic_compositor_video']}, 15:33:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:33:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'other-e10s': {'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['a11yr', 15:33:39 INFO - 'ts_paint', 15:33:39 INFO - 'tpaint', 15:33:39 INFO - 'sessionrestore', 15:33:39 INFO - 'sessionrestore_no_auto_restore', 15:33:39 INFO - 'tabpaint']}, 15:33:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['tsvgx', 15:33:39 INFO - 'tsvgr_opacity', 15:33:39 INFO - 'tart', 15:33:39 INFO - 'tscrollx', 15:33:39 INFO - 'cart']}, 15:33:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:33:39 INFO - 'tsvgr_opacity', 15:33:39 INFO - 'tart', 15:33:39 INFO - 'tscrollx', 15:33:39 INFO - 'cart']}, 15:33:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'talos_options': ['--disable-e10s'], 15:33:39 INFO - 'tests': ['tp5o']}, 15:33:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'tests': ['tp5o']}, 15:33:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'talos_options': ['--disable-e10s', 15:33:39 INFO - '--xperf_path', 15:33:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:33:39 INFO - 'tests': ['tp5n']}, 15:33:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:33:39 INFO - 'talos_options': ['--xperf_path', 15:33:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:33:39 INFO - 'tests': ['tp5n']}}, 15:33:39 INFO - 'talos.zip': {'path': '', 15:33:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:33:39 INFO - Running post-action listener: _resource_record_post_action 15:33:39 INFO - [mozharness: 2016-08-01 22:33:39.638430Z] Finished populate-webroot step (success) 15:33:39 INFO - [mozharness: 2016-08-01 22:33:39.638519Z] Running create-virtualenv step. 15:33:39 INFO - Running pre-action listener: _resource_record_pre_action 15:33:39 INFO - Running main action method: create_virtualenv 15:33:39 INFO - Creating virtualenv /builds/slave/test/build/venv 15:33:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:33:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:33:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:33:39 INFO - Using real prefix '/usr' 15:33:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:33:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:33:42 INFO - Installing pip.................done. 15:33:42 INFO - Return code: 0 15:33:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:33:42 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')]} 15:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:42 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')]} 15:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:42 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:42 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 15:33:42 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 15:33:42 INFO - Using env: {'DISPLAY': ':0', 15:33:42 INFO - 'HOME': '/home/cltbld', 15:33:42 INFO - 'LANG': 'en_US.UTF-8', 15:33:42 INFO - 'LANGUAGE': 'en_US:en', 15:33:42 INFO - 'LOGNAME': 'cltbld', 15:33:42 INFO - 'MAIL': '/var/mail/cltbld', 15:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:42 INFO - 'MOZ_NO_REMOTE': '1', 15:33:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:42 INFO - 'NO_EM_RESTART': '1', 15:33:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:42 INFO - 'PWD': '/builds/slave/test', 15:33:42 INFO - 'SHELL': '/bin/bash', 15:33:42 INFO - 'SHLVL': '1', 15:33:42 INFO - 'TERM': 'linux', 15:33:42 INFO - 'TMOUT': '86400', 15:33:42 INFO - 'USER': 'cltbld', 15:33:42 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:42 INFO - '_': '/tools/buildbot/bin/python'} 15:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:33:42 INFO - Cleaning up... 15:33:42 INFO - Return code: 0 15:33:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:33:42 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')]} 15:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:42 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')]} 15:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:42 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:42 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 15:33:42 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 15:33:42 INFO - Using env: {'DISPLAY': ':0', 15:33:42 INFO - 'HOME': '/home/cltbld', 15:33:42 INFO - 'LANG': 'en_US.UTF-8', 15:33:42 INFO - 'LANGUAGE': 'en_US:en', 15:33:42 INFO - 'LOGNAME': 'cltbld', 15:33:42 INFO - 'MAIL': '/var/mail/cltbld', 15:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:42 INFO - 'MOZ_NO_REMOTE': '1', 15:33:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:42 INFO - 'NO_EM_RESTART': '1', 15:33:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:42 INFO - 'PWD': '/builds/slave/test', 15:33:42 INFO - 'SHELL': '/bin/bash', 15:33:42 INFO - 'SHLVL': '1', 15:33:42 INFO - 'TERM': 'linux', 15:33:42 INFO - 'TMOUT': '86400', 15:33:42 INFO - 'USER': 'cltbld', 15:33:42 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:42 INFO - '_': '/tools/buildbot/bin/python'} 15:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:42 INFO - Downloading/unpacking psutil>=3.1.1 15:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:42 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 15:33:42 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 15:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:33:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:33:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:33:44 INFO - Installing collected packages: psutil 15:33:44 INFO - Running setup.py install for psutil 15:33:44 INFO - building 'psutil._psutil_linux' extension 15:33:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:33:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:33:44 INFO - building 'psutil._psutil_posix' extension 15:33:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:33:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:33:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:33:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:33:45 INFO - Successfully installed psutil 15:33:45 INFO - Cleaning up... 15:33:45 INFO - Return code: 0 15:33:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:33:45 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')]} 15:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:45 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')]} 15:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:45 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:45 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 15:33:45 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 15:33:45 INFO - Using env: {'DISPLAY': ':0', 15:33:45 INFO - 'HOME': '/home/cltbld', 15:33:45 INFO - 'LANG': 'en_US.UTF-8', 15:33:45 INFO - 'LANGUAGE': 'en_US:en', 15:33:45 INFO - 'LOGNAME': 'cltbld', 15:33:45 INFO - 'MAIL': '/var/mail/cltbld', 15:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:45 INFO - 'MOZ_NO_REMOTE': '1', 15:33:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:45 INFO - 'NO_EM_RESTART': '1', 15:33:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:45 INFO - 'PWD': '/builds/slave/test', 15:33:45 INFO - 'SHELL': '/bin/bash', 15:33:45 INFO - 'SHLVL': '1', 15:33:45 INFO - 'TERM': 'linux', 15:33:45 INFO - 'TMOUT': '86400', 15:33:45 INFO - 'USER': 'cltbld', 15:33:45 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:45 INFO - '_': '/tools/buildbot/bin/python'} 15:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:45 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 15:33:45 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 15:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:33:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:33:47 INFO - Installing collected packages: mozsystemmonitor 15:33:47 INFO - Running setup.py install for mozsystemmonitor 15:33:47 INFO - Successfully installed mozsystemmonitor 15:33:47 INFO - Cleaning up... 15:33:47 INFO - Return code: 0 15:33:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:33:47 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')]} 15:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:47 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')]} 15:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:47 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:47 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 15:33:47 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 15:33:47 INFO - Using env: {'DISPLAY': ':0', 15:33:47 INFO - 'HOME': '/home/cltbld', 15:33:47 INFO - 'LANG': 'en_US.UTF-8', 15:33:47 INFO - 'LANGUAGE': 'en_US:en', 15:33:47 INFO - 'LOGNAME': 'cltbld', 15:33:47 INFO - 'MAIL': '/var/mail/cltbld', 15:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:47 INFO - 'MOZ_NO_REMOTE': '1', 15:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:47 INFO - 'NO_EM_RESTART': '1', 15:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:47 INFO - 'PWD': '/builds/slave/test', 15:33:47 INFO - 'SHELL': '/bin/bash', 15:33:47 INFO - 'SHLVL': '1', 15:33:47 INFO - 'TERM': 'linux', 15:33:47 INFO - 'TMOUT': '86400', 15:33:47 INFO - 'USER': 'cltbld', 15:33:47 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:47 INFO - '_': '/tools/buildbot/bin/python'} 15:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:47 INFO - Downloading/unpacking jsonschema==2.5.1 15:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:47 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 15:33:47 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 15:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:33:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:49 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 15:33:49 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 15:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:33:49 INFO - warning: no files found matching '*.txt' 15:33:49 INFO - no previously-included directories found matching 'build' 15:33:49 INFO - no previously-included directories found matching 'dist' 15:33:49 INFO - no previously-included directories found matching '.git*' 15:33:49 INFO - Installing collected packages: jsonschema, functools32 15:33:49 INFO - Running setup.py install for functools32 15:33:49 INFO - warning: no files found matching '*.txt' 15:33:49 INFO - no previously-included directories found matching 'build' 15:33:49 INFO - no previously-included directories found matching 'dist' 15:33:49 INFO - no previously-included directories found matching '.git*' 15:33:49 INFO - Successfully installed jsonschema functools32 15:33:49 INFO - Cleaning up... 15:33:50 INFO - Return code: 0 15:33:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:33: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')]} 15:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33: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')]} 15:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:50 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:50 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 15:33:50 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 15:33:50 INFO - Using env: {'DISPLAY': ':0', 15:33:50 INFO - 'HOME': '/home/cltbld', 15:33:50 INFO - 'LANG': 'en_US.UTF-8', 15:33:50 INFO - 'LANGUAGE': 'en_US:en', 15:33:50 INFO - 'LOGNAME': 'cltbld', 15:33:50 INFO - 'MAIL': '/var/mail/cltbld', 15:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:50 INFO - 'MOZ_NO_REMOTE': '1', 15:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:50 INFO - 'NO_EM_RESTART': '1', 15:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:50 INFO - 'PWD': '/builds/slave/test', 15:33:50 INFO - 'SHELL': '/bin/bash', 15:33:50 INFO - 'SHLVL': '1', 15:33:50 INFO - 'TERM': 'linux', 15:33:50 INFO - 'TMOUT': '86400', 15:33:50 INFO - 'USER': 'cltbld', 15:33:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:50 INFO - '_': '/tools/buildbot/bin/python'} 15:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:33:50 INFO - Cleaning up... 15:33:50 INFO - Return code: 0 15:33:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:33: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')]} 15:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33: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')]} 15:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:50 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:50 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 15:33:50 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 15:33:50 INFO - Using env: {'DISPLAY': ':0', 15:33:50 INFO - 'HOME': '/home/cltbld', 15:33:50 INFO - 'LANG': 'en_US.UTF-8', 15:33:50 INFO - 'LANGUAGE': 'en_US:en', 15:33:50 INFO - 'LOGNAME': 'cltbld', 15:33:50 INFO - 'MAIL': '/var/mail/cltbld', 15:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:50 INFO - 'MOZ_NO_REMOTE': '1', 15:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:50 INFO - 'NO_EM_RESTART': '1', 15:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:50 INFO - 'PWD': '/builds/slave/test', 15:33:50 INFO - 'SHELL': '/bin/bash', 15:33:50 INFO - 'SHLVL': '1', 15:33:50 INFO - 'TERM': 'linux', 15:33:50 INFO - 'TMOUT': '86400', 15:33:50 INFO - 'USER': 'cltbld', 15:33:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:50 INFO - '_': '/tools/buildbot/bin/python'} 15:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:50 INFO - Downloading/unpacking blobuploader==1.2.4 15:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:50 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 15:33:50 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 15:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:33:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:52 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 15:33:52 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 15:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:33:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:52 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 15:33:52 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 15:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:53 INFO - Downloading docopt-0.6.1.tar.gz 15:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:33:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:33:53 INFO - Running setup.py install for blobuploader 15:33:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:33:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:33:53 INFO - Running setup.py install for requests 15:33:53 INFO - Running setup.py install for docopt 15:33:53 INFO - Successfully installed blobuploader requests docopt 15:33:53 INFO - Cleaning up... 15:33:53 INFO - Return code: 0 15:33:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:53 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')]} 15:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:53 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')]} 15:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:53 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:53 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 15:33:53 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 15:33:53 INFO - Using env: {'DISPLAY': ':0', 15:33:53 INFO - 'HOME': '/home/cltbld', 15:33:53 INFO - 'LANG': 'en_US.UTF-8', 15:33:53 INFO - 'LANGUAGE': 'en_US:en', 15:33:53 INFO - 'LOGNAME': 'cltbld', 15:33:53 INFO - 'MAIL': '/var/mail/cltbld', 15:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:53 INFO - 'MOZ_NO_REMOTE': '1', 15:33:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:53 INFO - 'NO_EM_RESTART': '1', 15:33:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:53 INFO - 'PWD': '/builds/slave/test', 15:33:53 INFO - 'SHELL': '/bin/bash', 15:33:53 INFO - 'SHLVL': '1', 15:33:53 INFO - 'TERM': 'linux', 15:33:53 INFO - 'TMOUT': '86400', 15:33:53 INFO - 'USER': 'cltbld', 15:33:53 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:53 INFO - '_': '/tools/buildbot/bin/python'} 15:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:53 INFO - Running setup.py (path:/tmp/pip-TKq2PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:53 INFO - Running setup.py (path:/tmp/pip-XvIDPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:54 INFO - Running setup.py (path:/tmp/pip-6G6svv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:54 INFO - Running setup.py (path:/tmp/pip-zzCJq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:54 INFO - Running setup.py (path:/tmp/pip-Khmz3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:54 INFO - Running setup.py (path:/tmp/pip-YNPPNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:54 INFO - Running setup.py (path:/tmp/pip-0FZ0vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:54 INFO - Running setup.py (path:/tmp/pip-mOjSaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:54 INFO - Running setup.py (path:/tmp/pip-8KxIwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:54 INFO - Running setup.py (path:/tmp/pip-oMK3St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:54 INFO - Running setup.py (path:/tmp/pip-XjJTlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:54 INFO - Running setup.py (path:/tmp/pip-IfyccP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:54 INFO - Running setup.py (path:/tmp/pip-wviuwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:54 INFO - Running setup.py (path:/tmp/pip-T1Lnpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:54 INFO - Running setup.py (path:/tmp/pip-MWCPsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:54 INFO - Running setup.py (path:/tmp/pip-LlLNDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:55 INFO - Running setup.py (path:/tmp/pip-piLKsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:33:55 INFO - Running setup.py install for manifestparser 15:33:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Running setup.py install for mozcrash 15:33:55 INFO - Running setup.py install for mozdebug 15:33:55 INFO - Running setup.py install for mozdevice 15:33:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Running setup.py install for mozfile 15:33:55 INFO - Running setup.py install for mozhttpd 15:33:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Running setup.py install for mozinfo 15:33:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Running setup.py install for mozInstall 15:33:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:33:55 INFO - Running setup.py install for mozleak 15:33:56 INFO - Running setup.py install for mozlog 15:33:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Running setup.py install for moznetwork 15:33:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Running setup.py install for mozprocess 15:33:56 INFO - Running setup.py install for mozprofile 15:33:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Running setup.py install for mozrunner 15:33:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Running setup.py install for mozscreenshot 15:33:56 INFO - Running setup.py install for moztest 15:33:56 INFO - Running setup.py install for mozversion 15:33:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:33:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:33:56 INFO - Cleaning up... 15:33:56 INFO - Return code: 0 15:33:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:56 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:56 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 15:33:56 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 15:33:56 INFO - Using env: {'DISPLAY': ':0', 15:33:56 INFO - 'HOME': '/home/cltbld', 15:33:56 INFO - 'LANG': 'en_US.UTF-8', 15:33:56 INFO - 'LANGUAGE': 'en_US:en', 15:33:56 INFO - 'LOGNAME': 'cltbld', 15:33:56 INFO - 'MAIL': '/var/mail/cltbld', 15:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:56 INFO - 'MOZ_NO_REMOTE': '1', 15:33:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:56 INFO - 'NO_EM_RESTART': '1', 15:33:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:56 INFO - 'PWD': '/builds/slave/test', 15:33:56 INFO - 'SHELL': '/bin/bash', 15:33:56 INFO - 'SHLVL': '1', 15:33:56 INFO - 'TERM': 'linux', 15:33:56 INFO - 'TMOUT': '86400', 15:33:56 INFO - 'USER': 'cltbld', 15:33:56 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:56 INFO - '_': '/tools/buildbot/bin/python'} 15:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:57 INFO - Running setup.py (path:/tmp/pip-PJWrJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:57 INFO - Running setup.py (path:/tmp/pip-9egweT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:57 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)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:57 INFO - Running setup.py (path:/tmp/pip-TiSonr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:57 INFO - Running setup.py (path:/tmp/pip-8z7xER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:57 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)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:57 INFO - Running setup.py (path:/tmp/pip-RsLmY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:57 INFO - Running setup.py (path:/tmp/pip-G95mLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:57 INFO - Running setup.py (path:/tmp/pip-rna8jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:57 INFO - Running setup.py (path:/tmp/pip-zf7afF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:57 INFO - Running setup.py (path:/tmp/pip-kbCWVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:57 INFO - Running setup.py (path:/tmp/pip-Cpjgzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:57 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)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:57 INFO - Running setup.py (path:/tmp/pip-vkMXDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:57 INFO - Running setup.py (path:/tmp/pip-6f6ij9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:58 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)) 15:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:58 INFO - Running setup.py (path:/tmp/pip-qfNTmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:58 INFO - Running setup.py (path:/tmp/pip-D3wGDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:58 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)) 15:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:58 INFO - Running setup.py (path:/tmp/pip-0w3zDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:58 INFO - Running setup.py (path:/tmp/pip-I3yaHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:58 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)) 15:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:58 INFO - Running setup.py (path:/tmp/pip-08KNo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:33:58 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)) 15:33:58 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)) 15:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:33:58 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)) 15:33:58 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)) 15:33:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:58 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 15:33:58 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 15:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:00 INFO - Downloading blessings-1.6.tar.gz 15:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:00 INFO - Installing collected packages: blessings 15:34:00 INFO - Running setup.py install for blessings 15:34:00 INFO - Successfully installed blessings 15:34:00 INFO - Cleaning up... 15:34:00 INFO - Return code: 0 15:34:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:00 INFO - Reading from file tmpfile_stdout 15:34:00 INFO - Current package versions: 15:34:00 INFO - argparse == 1.2.1 15:34:00 INFO - blessings == 1.6 15:34:00 INFO - blobuploader == 1.2.4 15:34:00 INFO - docopt == 0.6.1 15:34:00 INFO - functools32 == 3.2.3-2 15:34:00 INFO - jsonschema == 2.5.1 15:34:00 INFO - manifestparser == 1.1 15:34:00 INFO - mozInstall == 1.12 15:34:00 INFO - mozcrash == 0.17 15:34:00 INFO - mozdebug == 0.1 15:34:00 INFO - mozdevice == 0.48 15:34:00 INFO - mozfile == 1.2 15:34:00 INFO - mozhttpd == 0.7 15:34:00 INFO - mozinfo == 0.9 15:34:00 INFO - mozleak == 0.1 15:34:00 INFO - mozlog == 3.3 15:34:00 INFO - moznetwork == 0.27 15:34:00 INFO - mozprocess == 0.23 15:34:00 INFO - mozprofile == 0.28 15:34:00 INFO - mozrunner == 6.12 15:34:00 INFO - mozscreenshot == 0.1 15:34:00 INFO - mozsystemmonitor == 0.3 15:34:00 INFO - moztest == 0.7 15:34:00 INFO - mozversion == 1.4 15:34:00 INFO - psutil == 3.1.1 15:34:00 INFO - requests == 1.2.3 15:34:00 INFO - wsgiref == 0.1.2 15:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:00 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')]} 15:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:00 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')]} 15:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:00 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:34:00 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 15:34:00 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 15:34:00 INFO - Using env: {'DISPLAY': ':0', 15:34:00 INFO - 'HOME': '/home/cltbld', 15:34:00 INFO - 'LANG': 'en_US.UTF-8', 15:34:00 INFO - 'LANGUAGE': 'en_US:en', 15:34:00 INFO - 'LOGNAME': 'cltbld', 15:34:00 INFO - 'MAIL': '/var/mail/cltbld', 15:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:00 INFO - 'MOZ_NO_REMOTE': '1', 15:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:00 INFO - 'NO_EM_RESTART': '1', 15:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:00 INFO - 'PWD': '/builds/slave/test', 15:34:00 INFO - 'SHELL': '/bin/bash', 15:34:00 INFO - 'SHLVL': '1', 15:34:00 INFO - 'TERM': 'linux', 15:34:00 INFO - 'TMOUT': '86400', 15:34:00 INFO - 'USER': 'cltbld', 15:34:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:00 INFO - '_': '/tools/buildbot/bin/python'} 15:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:34:00 INFO - Cleaning up... 15:34:01 INFO - Return code: 0 15:34:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:01 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 0x2639030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:34:01 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 15:34:01 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 15:34:01 INFO - Using env: {'DISPLAY': ':0', 15:34:01 INFO - 'HOME': '/home/cltbld', 15:34:01 INFO - 'LANG': 'en_US.UTF-8', 15:34:01 INFO - 'LANGUAGE': 'en_US:en', 15:34:01 INFO - 'LOGNAME': 'cltbld', 15:34:01 INFO - 'MAIL': '/var/mail/cltbld', 15:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:01 INFO - 'MOZ_NO_REMOTE': '1', 15:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:01 INFO - 'NO_EM_RESTART': '1', 15:34:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:01 INFO - 'PWD': '/builds/slave/test', 15:34:01 INFO - 'SHELL': '/bin/bash', 15:34:01 INFO - 'SHLVL': '1', 15:34:01 INFO - 'TERM': 'linux', 15:34:01 INFO - 'TMOUT': '86400', 15:34:01 INFO - 'USER': 'cltbld', 15:34:01 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:01 INFO - '_': '/tools/buildbot/bin/python'} 15:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:34:01 INFO - Cleaning up... 15:34:01 INFO - Return code: 0 15:34:01 INFO - Running post-action listener: _resource_record_post_action 15:34:01 INFO - Running post-action listener: _start_resource_monitoring 15:34:01 INFO - Starting resource monitoring. 15:34:01 INFO - [mozharness: 2016-08-01 22:34:01.246496Z] Finished create-virtualenv step (success) 15:34:01 INFO - [mozharness: 2016-08-01 22:34:01.246933Z] Running install step. 15:34:01 INFO - Running pre-action listener: _resource_record_pre_action 15:34:01 INFO - Running main action method: install 15:34:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:01 INFO - Reading from file tmpfile_stdout 15:34:01 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:01 INFO - Reading from file tmpfile_stdout 15:34:01 INFO - Output received: 15:34:01 INFO - Usage: mozinstall [options] installer 15:34:01 INFO - Options: 15:34:01 INFO - -h, --help show this help message and exit 15:34:01 INFO - -d DEST, --destination=DEST 15:34:01 INFO - Directory to install application into. [default: 15:34:01 INFO - "/builds/slave/test"] 15:34:01 INFO - --app=APP Application being installed. [default: firefox] 15:34:01 INFO - mkdir: /builds/slave/test/build/application 15:34:01 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'] 15:34:01 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 15:34:17 INFO - Reading from file tmpfile_stdout 15:34:17 INFO - Output received: 15:34:17 INFO - /builds/slave/test/build/application/firefox/firefox 15:34:17 INFO - Running post-action listener: _resource_record_post_action 15:34:17 INFO - [mozharness: 2016-08-01 22:34:17.588534Z] Finished install step (success) 15:34:17 INFO - [mozharness: 2016-08-01 22:34:17.588783Z] Running run-tests step. 15:34:17 INFO - Running pre-action listener: _resource_record_pre_action 15:34:17 INFO - Running main action method: run_tests 15:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:34:17 INFO - Python 2.7.3 15:34:17 INFO - Return code: 0 15:34:17 INFO - grabbing minidump binary from tooltool 15:34: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')]} 15:34:17 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 0x28f83b0>, '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 0x28f92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:34:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:34:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:34:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:34:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:34:17 INFO - Return code: 0 15:34:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:34:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:34:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:34:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:34:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:34:17 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/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 15:34:17 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/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 15:34:17 INFO - Using env: {'DISPLAY': ':0', 15:34:17 INFO - 'HOME': '/home/cltbld', 15:34:17 INFO - 'LANG': 'en_US.UTF-8', 15:34:17 INFO - 'LANGUAGE': 'en_US:en', 15:34:17 INFO - 'LOGNAME': 'cltbld', 15:34:17 INFO - 'MAIL': '/var/mail/cltbld', 15:34:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:34:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:17 INFO - 'MOZ_NO_REMOTE': '1', 15:34:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:17 INFO - 'NO_EM_RESTART': '1', 15:34:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:17 INFO - 'PWD': '/builds/slave/test', 15:34:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:34:17 INFO - 'SHELL': '/bin/bash', 15:34:17 INFO - 'SHLVL': '1', 15:34:17 INFO - 'TERM': 'linux', 15:34:17 INFO - 'TMOUT': '86400', 15:34:17 INFO - 'USER': 'cltbld', 15:34:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377', 15:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:17 INFO - '_': '/tools/buildbot/bin/python'} 15:34:17 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/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 15:34:17 INFO - mozversion application_buildid: 20160801140551 15:34:17 INFO - mozversion application_changeset: b2a6aaf59783cecf8cde63fe51fbd65e14b026df 15:34:17 INFO - mozversion application_display_name: Nightly 15:34:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:34:17 INFO - mozversion application_name: Firefox 15:34:17 INFO - mozversion application_remotingname: firefox 15:34:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:34:17 INFO - mozversion application_vendor: Mozilla 15:34:17 INFO - mozversion application_version: 50.0a1 15:34:17 INFO - mozversion platform_buildid: 20160801140551 15:34:17 INFO - mozversion platform_changeset: b2a6aaf59783cecf8cde63fe51fbd65e14b026df 15:34:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:34:17 INFO - mozversion platform_version: 50.0a1 15:34:17 INFO - using testdate: 1470090857 15:34:17 INFO - actual date: 1470090857 15:34:17 INFO - starting webserver on 'localhost:39535' 15:34:17 INFO - SUITE-START | Running 2 tests 15:34:17 INFO - TEST-START | dromaeo_css 15:34:17 INFO - operating with platform_type : linux_ 15:34:17 INFO - Initialising browser for dromaeo_css test... 15:34:17 INFO - TEST-INFO | started process 8628 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMPyZFF/profile http://localhost:39535/getInfo.html) 15:34:22 INFO - PROCESS | 8628 | __metrics Screen width/height:1600/1200 15:34:22 INFO - PROCESS | 8628 | colorDepth:24 15:34:22 INFO - PROCESS | 8628 | Browser inner width/height: 1024/697 15:34:22 INFO - PROCESS | 8628 | __metrics 15:34:23 INFO - PROCESS | 8628 | 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" 15:34:23 INFO - PROCESS | 8628 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:34:24 INFO - TEST-INFO | 8628: exit 0 15:34:24 INFO - Browser initialized. 15:34:24 INFO - Running cycle 1/1 for dromaeo_css test... 15:34:24 INFO - TEST-INFO | started process 8704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMPyZFF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:36 INFO - PROCESS | 8704 | RSS: Main: 157577216 15:34:36 INFO - PROCESS | 8704 | 15:35:28 INFO - PROCESS | 8704 | 1470090928178 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] 15:37:39 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39535/tests/dromaeo/cssquery-ext.html) 15:37:41 INFO - PROCESS | 8704 | RSS: Main: 246669312 15:37:41 INFO - PROCESS | 8704 | 15:40:43 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-ext.html (next: http://localhost:39535/tests/dromaeo/cssquery-jquery.html) 15:40:44 INFO - PROCESS | 8704 | RSS: Main: 245698560 15:40:44 INFO - PROCESS | 8704 | 15:43:46 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39535/tests/dromaeo/cssquery-mootools.html) 15:43:46 INFO - PROCESS | 8704 | RSS: Main: 189575168 15:43:46 INFO - PROCESS | 8704 | 15:46:49 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39535/tests/dromaeo/cssquery-prototype.html) 15:46:49 INFO - PROCESS | 8704 | RSS: Main: 188051456 15:46:49 INFO - PROCESS | 8704 | 15:49:52 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39535/tests/dromaeo/cssquery-yui.html) 15:49:53 INFO - PROCESS | 8704 | RSS: Main: 189751296 15:49:53 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | Cycle 1(1): loaded http://localhost:39535/tests/dromaeo/cssquery-yui.html (next: http://localhost:39535/tests/dromaeo/cssquery-dojo.html) 15:52:56 INFO - PROCESS | 8704 | RSS: Main: 282632192 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | __start_tp_report 15:52:56 INFO - PROCESS | 8704 | _x_x_mozilla_page_load 15:52:56 INFO - PROCESS | 8704 | _x_x_mozilla_page_load_details 15:52:56 INFO - PROCESS | 8704 | |i|pagename|runs| 15:52:56 INFO - PROCESS | 8704 | |0;dojo.html;2627.133046094789;2629.9079532216374;2641.6829980402354;2663.5205662980657;2663.773579245764;4623.375844261032;4737.455192652846;4742.525747425256;4778.689385189965;4808.807647694092;4700.6474514411475;4741.359916411284;4743.098811225865;4747.8338258160975;4813.4223893132785;4294.613484786369;4304.569543045702;4359.847405340814;4410.2282100632265;4484.66365022623;4404.163208990298;4424.380586717846;4463.598276155145;4477.619402350804;4481.282994720846;4226.154769046172;4304.634106100985;4357.607815296638;4365.9126817463475;4451.688381813275;4283.828646854122;4298.634616057639;4316.82732690692;4343.04453020335;4366.519682834885;4284.957150428487;4342.717723347972;4350.7172033818015;4355.73865568067;4391.494978077498;4615.376924115235;4729.385179926588;4793.2810078488155;4819.590334821544;4841.15279826029;4960.553550180465;5021.39743230811;5060.21566657165;5070.3915530136255;5072.67027643202;5140.665856719302;5146.485351464861;5149.356330458692;5179.559737422286;5183.222516622499;4617.861464156013;4719.244920812654;4778.426588809365;4789.808407663654;4830.203016502238;5217.217417387422;5267.578593712494;5280.973595132;5283.286756287852;5291.1798671206025;5015.448300686921;5018.799248030038;5058.140116180185;5070.416902056237;5070.898582028339;5102.158143906214;5126.53861152498;5157.277981082651;5160.92258616121;5162.586993040548;4889.095517329305;5005.874853128701;5019.272205530251;5047.621428392885;5105.591552675777;5034.798608055637;5038.395392552917;5051.393832740095;5051.898962020739;5082.110630639623;4351.042770590465;4363.847265345697;4373.147236288957;4375.212461756914;4417.359482874966;3692.316921369555;3731.776093434403;3755.793431361247;3796.981015094962;3845.288621605012;4371.169477799272;4384.802683879233;4386.670999675037;4427.911441771146;4431.7562534060935;5129.9230511542355;5131.512506311894;5136.768845401966;5136.794528218829;5176.818811341585;4242.893927651833;4315.546867578922;4350;4366.257736184856;4397.494288156903;4565.315202719552;4573.131105090022;4592.954070459253;4629.051044535862;4637.118947399983;4754.096721622881;4757.476677565534;4763.118823017822;4777.3072904429355;4779.06808172403;4896.951030489649;5086.237064440364;5086.720230387365;5112.335396398445;5120.846374608767;4822.156122678587;4849.709017458964;4856.077345304381;4877.219644856806;4902.240152776325;5058.671186372874;5065.39215294167;5068.366454193226;5071.290019397361;5071.797128114834;4927.531884470969;5043.445221025758;5056.64603477753;5088.669236499616;5180.326557547494;4940.654154209171;4941;4941.530554597308;4944.851654450372;4958.082754690394;4958.256261560795;4966.255061740768;4967.130752118293;4968.776405061709;4996.075725990703;4729.314249433744;4791.592714619296;4837.419509658864;4851.102546028855;4932.679375840559;4959.380077490314;4962.652614316963;4966.602671786321;4968.27959945813;4981;4865.756712164449;4871.585915197248;4873.098476781806;4873.975630121827;4918.704877707349 15:52:56 INFO - PROCESS | 8704 | |1;ext.html;570.700382299293;571.0891128649805;574.2448679985815;583.3087790967702;597.2057163971915;29201.269968250803;39474;40888.18223635526;41094.58905410945;44368.778156109176;41217.58782412175;41760.58239417605;41780.16439671205;41789.164216715726;41805.58194418055;7431.29402706742;7526.962365188167;7557.05536807899;7563.130240022385;7569.432292578051;7598.354139898088;7665.5783931883725;7699.384049276045;7711.958885550463;7717.2668596483245;7741.148473667892;7765.922340776605;7800.258975397328;7812.843743125134;7816.49192802466;7733.961330193341;7757.806054848617;7767.339776119008;7768.65041073153;7769.1842356552315;7641.541507509512;7653.846923061507;7697.615119244071;7714.652840622185;7716.807079822993;38799.61200387988;39238.411423828664;39303.60696393028;39363;39396.212075758325;2956.9852150739325;3069.539569064636;3295.2915123248376;3626.691731202851;3744.925101497982;27358.452830943468;29371.265718356997;29766.851165744032;30025.099247022623;30209.395812083854;3484.756067075306;3531.6644918732677;3548.432250839853;3563.572371315433;3623.655752703515;257293;257382;258959;259799;259919;54462;54849.45150548523;54883.17675234874;54934.72532637391;54958;32272.515912261333;33315;33454.16364590856;57948.420515795144;58182;14484.565463036126;14645.780313295309;14673.486427974969;14739.484118055816;14777.704445911237;10485;10620.681379558626;10621.362718236931;10623.150147988143;10623.575056997788;17815.910920445316;17936.9103154486;18027.549311267325;18074.728879066824;18081.186346614435;6860.622665753332;6898.550434969517;6967.442604591621;7045.089975063889;7057.435405167575;790.6442101054548;887.3655336434481;920.852663573825;993.5380048277585;1024.2522958240527;26950.460990780073;28179;28758;29036.41927161445;29094.41811163765;925.8287216864901;926.2080920812742;930.1721467893581;930.4882314726873;966.7341481092615;14430.567082987318;15021.098734075771;15165.393384264726;15225.31486083129;15332.770008449883;372334.1383293066;376533;377390;377418;377569;49886.50113498818;50563.49436505588;50613.24080138801;50651;50705.746471267405;1072.6406653771073;1152.665726939195;1197.21582363552;1211.2127117373602;1228.5270170984236;28219.71780282171;29319.560206597343;29643;30334.84832575823;30387.2403189922;42133.78933105315;42684;42695.786521067195;42777.358339625556;42844.571554284055;285950;288061;288281;289167;290629;24741.752582474666;25452.363690907874;25841.741582583934;25870.353241169123;26890;41552.58447415487;41607;41656.79171604244;42333.36499952441;43147;35470.46794298139;35883.28233435265;36073.27853442864;36409.45385819162;36786;35629.64370356263;35738.463923040654;36274.455883161245;36397.45403818892;36479.635203647624;35202.47196292006;36374;36759.26481470409;38631.227375452894;39302.80348598239;30764.69235307618;32847.83576082104;33438.83280583582;33499.665003349655;33792.83103584466;25153.119640812485;25298.620520692573;25379.238622841345;25714.485710286062;26043.348916277246 15:52:56 INFO - PROCESS | 8704 | |2;jquery.html;335.5771727623194;370.10065540736;372.12179256953584;373.55920014383025;388.49883650091385;12513.311767852776;16480.09359485228;16891.239894204777;17921.10394480274;19121.904390478026;17129.657406851857;17171;17658.470245892644;17822.554436139104;17832.1975511102;4981.227909674007;5128.282040514331;5176.430592634807;5208.401033881095;5209.739513024334;3738.532683414573;5083.085044691954;5271.630985830993;5271.683698978054;5729.5702822288495;5940.257467816523;5984.581079324449;5985;5988.700564971756;5997.310309314419;5825.582383310942;5975.910361344583;6074.088886666991;6076.513878889678;6109.389061093855;5390.865228369283;5399.352077750695;5403.7027963462;5403.810866619689;5408.972955135238;20780.688289675665;20949.790502094937;20998.475038124016;21038.5792284155;21448.463788405257;1956.7064940258933;2050.395133435625;2067;2069.5343547701796;2585.8060645451665;14348.92825535876;14451.855481445262;15035.624109397244;15277.77777777787;15298.847011529853;1850.463365623981;1858.1359667754423;1914.119505027675;1928.8360489358422;1993.7308463357406;82174.58912705458;82316.58841705753;82898.17101828965;84400;87594.5620271901;16909.393607607257;16974.236159372856;17028.31886724542;17264.395746148824;18650.813491865178;29478;29562.113136605938;29593;29608.407831843244;29674;8924.553772311489;9189.264858811279;9301.395409298373;9507.524623768784;9541.809163816824;7774.689012439552;7799.181085986003;7931.762047138595;8009.959950200328;8185.386096042702;8134.430589858653;8240.981777612495;8276.503409795432;8457.196566326189;8565.743027709179;3271.9018429447156;3399.1502124468884;3422.3668621305137;3425.160835595295;3518.894433167009;782.7495201535454;816.6610856494607;827.7889138269703;854.273867212864;910.3763921713647;16454.734484964934;16576.07747991004;17786.733199002025;18045;18070.277188912558;846.2974486621033;863.926566241864;885.9069797671281;886.534569351083;925.4308600210957;9374.95312523433;9812;9823.803523929482;9856.31005829585;10229.948850255701;359099;359439;359663;362821;367763;77828;77859.2214077852;79033.60483197548;80802;80880.59559702165;866.1771317248639;880.2693764175718;881.18930583863;887.2768693514765;892.205936716323;15343.309551070452;15401.306941187897;15579.610509737347;15649.452269170524;16004.359825606845;38435;38608.80695596504;39108.413373798845;39238.607613924636;39517.80241098776;48461;52633;52738.47261527489;53600.73199633977;54858.45141548693;6822.692978815866;7175.425965922818;7206.747763828241;7422.146453157956;7578.734744283924;9850.753731156778;10866.293690910066;11781.587644432404;12007.279563226235;12152.57465988686;10537.262391632512;10784.730381740519;10785.352878827296;10829.29609575375;11260.718482038015;10173.593056277667;10178.847317290394;10330.896691032993;10643.095336896451;10842.457877106272;10020.498975051072;10123.392596444237;10513.264071515227;10784.676459706221;11056.557737690724;6619.669016549057;6682.465402767864;6767.35710107539;6770.593764374154;7075;8591.484510929364;10797.460126993776;10853.782924341627;10866.728331791452;10905.073068788925 15:52:56 INFO - PROCESS | 8704 | |3;mootools.html;1250.112420181671;1351.1420248142426;1382.0532934939563;1447.7176950494652;1678.3622223555046;6291.528135389847;7220.24187460317;7508.249175082489;7515.4739168258175;7690.346320562745;7446.106467223941;7499.625018749054;7504.137024242214;7510.286522780327;7524.134724506684;2193.7806219378053;2225.5660146271484;2226.5880812049822;2229.0972156276653;2229.4092065602626;2098.5488120054183;2132.6694362373855;2174.499865031044;2175.2169219081197;2175.3473957812594;2136.241634219853;2152.2789865395034;2162.7296587926508;2163.8485306028583;2177.8911054447294;2123.4054464749893;2125.5430082532253;2131.2221039320534;2131.3073251193346;2132.349633361824;2043.6117137743781;2079.4073688998715;2080.552681173555;2081.42760740796;2100.978990210094;7257.782266532013;7332.376747976428;7397.371223446014;7407.555546667218;7546.924530754677;4255.319148936186;4451.043025749447;4464.486584042812;4472.731636101845;4480.955190448087;2922.576226447153;2966.5253559430603;3030.499967505359;3034.757219422441;3046.1166261784056;921.5576523268802;922.6263363337791;933.929955253352;934.5794392523392;939.5349302095439;9858.9507052465;9919.90080099197;9922.801543969152;9931.106200441995;9951.75120621983;3891.2412079644764;4034.8587799427464;4037.414574886625;4039.7980100994832;4275.679324050709;7990.480618759762;8144.511329320259;8156.388270879589;8157.2250636189465;8186.959065204755;4532.4334458192725;4535.115652447741;4538;4543.159515489645;4550.13547425988;3555.768875023115;3933.547642021147;4130.0294430808735;4226.323788193874;4232.25935461293;3349.6650334966307;3376.020953923384;3476.4959080933627;3531.858725650945;3549.254656522159;1014.7716763728102;1025.220832167558;1033.2715435617872;1039.2569312941284;1041.5156952017348;785.7171418289412;810.9756707298791;812.0174588747564;812.3785304242259;818.3698552114957;4898.069366820364;4925.729084900365;4963.528464795839;5041.269015992661;5214.6610470320065;409.6968243499789;410.26152924735356;411.29052384636265;411.7262020756223;414.1179288116293;3186.601674790651;3263.918402039968;3264;3307.8676852925614;3411.6076651184935;8927.151920567536;9293.674721384854;9325.207357374564;9333.113354231336;9340.57967391469;9270.768230794283;9274.30442716799;9290.210332121711;9294.674686385944;9295.67465138717;7046.5067445279365;7153.856922861513;7177.138743350832;7182.42540596751;7187.5687458752645;4814.374131362901;4972.502749725143;4993.325901003318;5158.767855446589;5195.506426889439;8286.792830179053;8496.70261540843;8512.404131710968;8602.18279263469;8784.736457906272;8560.357973152162;8619.353548484014;8738.300935925237;8740.562971851508;8832.690855820272;6113.327533971348;6640.369164929324;7024.5082844200415;7195.496315257882;7197.388222001188;7037.577745335297;7204.135503739585;7286.854262914818;7307.561546307238;7436.888446673413;6305.432511074026;6342.673367233953;6468.902966455413;6576.967115164393;6636.734530618721;6436.163298771317;6458.935410645833;6495.870082598227;6581.769638062645;6630.53586248958;6102.4812890902995;6257.68711564429;6262.405071518198;6271.874562508633;6275.058741188858;4400.779961001873;4423;4487.41663583732;4505.504394516666;4566.337881007302;4466.754328511833;4494.617957473652;4536.160810250114;4551.08978204367;4564.612007979226 15:52:56 INFO - PROCESS | 8704 | |4;prototype.html;285.8323747491497;297.317655979527;297.3933176320307;301.9788614796964;303.62047440699126;4866.075445665326;4872.512748725126;4894.877628059291;4906.19047857104;4913.164761990462;4800.807967681298;4812.9518704812945;4825.445073816503;4836.226203807391;4840.273958906157;3627.8548858045647;3726.7204959628;3746.250749850027;3782.300274449222;3814.4850445189822;3812.752171108869;3830.291396091732;3831.4291140632345;3831.67430768385;3833.367494363427;3767.321882061228;3779.622037796226;3798.65812076913;3800.0239875264847;3824.2160357126722;3624.800635965021;3660.7803531788045;3728.850845966158;3774.9056273593105;3802.505674262356;3356.194513316811;3379.611344695367;3381.3913495570787;3383.6278009418943;3400.2179498715186;4716.268978308368;4723.95276047242;4735.124002059595;4739.265413860822;4758.381410416659;4275.615194632467;4295.8496452624;4324.935125973113;4366.8253269869165;4377.824887004516;4958.752062396866;4978.9751051244875;4986.825461108844;4999.275105109775;5009.298698182258;3693.907652308687;3702.5186725725753;3704.073981504624;3709.3508635988865;3714.182879766474;5064.366954130734;5255.343082114736;5303.992241474146;5308.867278318035;5322.414534401213;3779.7526816150603;3782.9432558511644;3784.9243015140096;3808.980955095207;3815.523059617548;3835.4055121456217;3839.520059992501;3847.9038024049623;3860.9227815444096;3874.903127421837;4333.978330108329;4348.369486424451;4359.978200108979;4367.475902891673;4369.781510924441;4794.232922732346;5009.323741294951;5042.0084066793415;5055.823046193365;5058.165402708585;1301.531448678475;1309.214471317221;1311.1608570514877;1311.6917524381763;1314.5399110311314;867.4775768526196;878.7056336127469;896.2471523919938;897.2059727141396;909.667971190524;4401.207782599099;4411.18393097278;4429.247028005247;4434.977825110854;4466.888327791831;5142.691438513626;5153.227015947563;5155.252488389163;5163.845084647466;5171.319321220619;4272.4445822042935;4396.516383197845;4408.4269045024585;4415;4422.872167597242;2704.60642331538;2720.265528307346;2749.752522273005;2752.6972033076345;2758.6689597248055;4845.6608037437745;4855.786053486629;4860.416749989953;4860.708357498562;4864.270359446112;4994.650374473825;5255.5795536357;5284.550813180846;5299.284596579412;5316.282301889196;4830.058138662997;4839.1531481991215;4846.80612775485;4846.95153048465;4856.854294371104;5127.846304552022;5159.432462429055;5177.559907407913;5226.686398816083;5227.843164705065;4863.537963893425;4996.850724333496;5085.644004919768;5114.360704911886;5131.690739167464;4920.803167873388;4946.826861059845;4950.504949505164;4970.975145124396;4981.4769449207315;3738.607446218217;3748.2503499299705;3790.6209379061875;3790.938537209586;3794.734368594172;5115.514026167508;5265.973670131625;5319.042572337016;5321.7339133042415;5323.787048518016;5080.517350851663;5080.847574572768;5082.593392528515;5082.923756143587;5087.3132127163835;5083.339165908408;5085.618578606545;5135.815794207466;5144.9742751286;5148.794048238028;4946.851594452172;4987.351644286219;5017.573506251863;5093.236014597692;5103.8468845934685;4886.633502487257;4971.37857767779;5081.644284900021;5091.87270318245;5124.923126153185;5008.273800299009;5020.447750747342;5049.57078648319;5065.442801291929;5066.645334826527 15:52:56 INFO - PROCESS | 8704 | |5;yui.html;626.5582764151274;705.5555000349779;707.961062141582;722.2777222777223;730.4959577891258;12899.29053902035;14156;14264.0728352657;14271.643208919782;14276.214808185545;13614.387352569134;13758.587242382719;13766.38051287692;13802.171869687802;13806.516771912986;618.9257289125288;753.7512620835112;757.9469437139403;777.0985656638301;780.2977320411646;777.4324742937658;781.1290411191537;781.1446466119619;781.3592853859838;781.7654703588929;806.0408114343941;808.4300568099505;808.5795386399069;808.8948436703221;809.0291650019971;649.6069877723957;797.6011994003028;813.1624426840364;818.8034871630825;822.6462621072936;597.5010865926956;597.5309382135047;598.769473752604;598.8892054969845;599.1701493431628;13084.149530280501;13118.147320424141;13120.147190432592;13129.343532823415;13146.737065258641;12446.377681115908;12708.682282942907;12884.226946383247;13077.607671769862;13274.86725132746;11648.417579121011;11878.346690931996;11895.821562676567;11970.640880773592;12276;4044.8786536403954;4056.391541268804;4081.0613558881623;4220.198162349144;4253.064325848309;66068.49349286809;67757.32242677608;69000.30999689989;69147.65426172837;69266;9625.133737963477;9964.10323070927;10203.795924081476;10247.538860751283;10322.741931451774;18410.81589184118;18670.253189872525;18682.439526814218;18709.345172919155;18712;6397.232332120176;6508.349165083454;6518.315576864456;6784.660766961731;6864.622445765531;3654.8903532894055;3654.9817250913575;3658;3683.7605555639334;3733.533308336458;2739.315171207198;2822.7741780657498;2837.744602985741;2842.5878247653973;2846.6014757933904;418.4685449479205;497.3310896843739;500.70458429526025;501.6388200495637;506.43279526929336;454.1054123376942;547.7808876449373;621.6798348850346;628.6385328436168;633.4773811605444;7629.2752188542;7789.261154645802;7988.960055199686;8009.4393392463135;8100.635471403801;485.2963203355149;580.5065694160014;595.8361450601119;603.3407384214723;607.4563265877015;3926.7447615904875;3964.731285988456;3965.5439624442984;3975.2447035063246;4066.9796651017145;75532.62233688796;94582;98580;101760;104931.4753426228;80812.59593701994;80935.19064809394;81582.18417815864;81730;82987.17012829916;70251.2974870255;70627;70745.29254707387;73492;73728;8025.638846252049;8141.592920354077;8190.180981901762;8482.491050536988;8524.659013639384;36296.81851590725;39258.21483570256;43053.78473107614;44031.559684402746;47291.290630641255;49119.26321105258;61806.38193618006;63459.36540634534;63552.046719300175;63569;10688.091512221548;10846.349219046882;11156.553737850396;11247.381394023407;11298.548058077586;12947.935260323638;13229.603111906657;13250.734985300047;13625.93187034098;13709.245991470565;11326.886731132583;11472.139589530652;11644.41777911118;11892.643220703392;11997.580084696994;11436.94281528587;11456.083513319081;11634.592789252336;11683.357415342238;12288.1398302118;10795.784084318428;10804.891951080388;11148.498317575566;11364.94317528407;11503.424828758361;10070.244731645302;10111.342762720695;10335.534900929326;10567.471626418803;10649.680509584725;10119;10336.793264134525;10342.586296548054;10347;10375.429351385746 15:52:56 INFO - PROCESS | 8704 | __end_tp_report 15:52:56 INFO - PROCESS | 8704 | __start_cc_report 15:52:56 INFO - PROCESS | 8704 | _x_x_mozilla_cycle_collect,980 15:52:56 INFO - PROCESS | 8704 | __end_cc_report 15:52:56 INFO - PROCESS | 8704 | __startTimestamp1470091976778__endTimestamp 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | ------- Summary: start ------- 15:52:56 INFO - PROCESS | 8704 | Number of tests: 6 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#0] dojo.html Cycles:165 Average:4703.41 Median:4837.42 stddev:498.20 (10.3%) stddev-sans-first:472.36 15:52:56 INFO - PROCESS | 8704 | Values: 2627.1 2629.9 2641.7 2663.5 2663.8 4623.4 4737.5 4742.5 4778.7 4808.8 4700.6 4741.4 4743.1 4747.8 4813.4 4294.6 4304.6 4359.8 4410.2 4484.7 4404.2 4424.4 4463.6 4477.6 4481.3 4226.2 4304.6 4357.6 4365.9 4451.7 4283.8 4298.6 4316.8 4343.0 4366.5 4285.0 4342.7 4350.7 4355.7 4391.5 4615.4 4729.4 4793.3 4819.6 4841.2 4960.6 5021.4 5060.2 5070.4 5072.7 5140.7 5146.5 5149.4 5179.6 5183.2 4617.9 4719.2 4778.4 4789.8 4830.2 5217.2 5267.6 5281.0 5283.3 5291.2 5015.4 5018.8 5058.1 5070.4 5070.9 5102.2 5126.5 5157.3 5160.9 5162.6 4889.1 5005.9 5019.3 5047.6 5105.6 5034.8 5038.4 5051.4 5051.9 5082.1 4351.0 4363.8 4373.1 4375.2 4417.4 3692.3 3731.8 3755.8 3797.0 3845.3 4371.2 4384.8 4386.7 4427.9 4431.8 5129.9 5131.5 5136.8 5136.8 5176.8 4242.9 4315.5 4350.0 4366.3 4397.5 4565.3 4573.1 4593.0 4629.1 4637.1 4754.1 4757.5 4763.1 4777.3 4779.1 4897.0 5086.2 5086.7 5112.3 5120.8 4822.2 4849.7 4856.1 4877.2 4902.2 5058.7 5065.4 5068.4 5071.3 5071.8 4927.5 5043.4 5056.6 5088.7 5180.3 4940.7 4941.0 4941.5 4944.9 4958.1 4958.3 4966.3 4967.1 4968.8 4996.1 4729.3 4791.6 4837.4 4851.1 4932.7 4959.4 4962.7 4966.6 4968.3 4981.0 4865.8 4871.6 4873.1 4874.0 4918.7 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#1] ext.html Cycles:180 Average:46427.81 Median:36772.63 stddev:81915.20 (222.8%) stddev-sans-first:82072.64 15:52:56 INFO - PROCESS | 8704 | Values: 570.7 571.1 574.2 583.3 597.2 29201.3 39474.0 40888.2 41094.6 44368.8 41217.6 41760.6 41780.2 41789.2 41805.6 7431.3 7527.0 7557.1 7563.1 7569.4 7598.4 7665.6 7699.4 7712.0 7717.3 7741.1 7765.9 7800.3 7812.8 7816.5 7734.0 7757.8 7767.3 7768.7 7769.2 7641.5 7653.8 7697.6 7714.7 7716.8 38799.6 39238.4 39303.6 39363.0 39396.2 2957.0 3069.5 3295.3 3626.7 3744.9 27358.5 29371.3 29766.9 30025.1 30209.4 3484.8 3531.7 3548.4 3563.6 3623.7 257293.0 257382.0 258959.0 259799.0 259919.0 54462.0 54849.5 54883.2 54934.7 54958.0 32272.5 33315.0 33454.2 57948.4 58182.0 14484.6 14645.8 14673.5 14739.5 14777.7 10485.0 10620.7 10621.4 10623.2 10623.6 17815.9 17936.9 18027.5 18074.7 18081.2 6860.6 6898.6 6967.4 7045.1 7057.4 790.6 887.4 920.9 993.5 1024.3 26950.5 28179.0 28758.0 29036.4 29094.4 925.8 926.2 930.2 930.5 966.7 14430.6 15021.1 15165.4 15225.3 15332.8 372334.1 376533.0 377390.0 377418.0 377569.0 49886.5 50563.5 50613.2 50651.0 50705.7 1072.6 1152.7 1197.2 1211.2 1228.5 28219.7 29319.6 29643.0 30334.8 30387.2 42133.8 42684.0 42695.8 42777.4 42844.6 285950.0 288061.0 288281.0 289167.0 290629.0 24741.8 25452.4 25841.7 25870.4 26890.0 41552.6 41607.0 41656.8 42333.4 43147.0 35470.5 35883.3 36073.3 36409.5 36786.0 35629.6 35738.5 36274.5 36397.5 36479.6 35202.5 36374.0 36759.3 38631.2 39302.8 30764.7 32847.8 33438.8 33499.7 33792.8 25153.1 25298.6 25379.2 25714.5 26043.3 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#2] jquery.html Cycles:180 Average:25200.64 Median:19498.65 stddev:60204.20 (308.8%) stddev-sans-first:60344.14 15:52:56 INFO - PROCESS | 8704 | Values: 335.6 370.1 372.1 373.6 388.5 12513.3 16480.1 16891.2 17921.1 19121.9 17129.7 17171.0 17658.5 17822.6 17832.2 4981.2 5128.3 5176.4 5208.4 5209.7 3738.5 5083.1 5271.6 5271.7 5729.6 5940.3 5984.6 5985.0 5988.7 5997.3 5825.6 5975.9 6074.1 6076.5 6109.4 5390.9 5399.4 5403.7 5403.8 5409.0 20780.7 20949.8 20998.5 21038.6 21448.5 1956.7 2050.4 2067.0 2069.5 2585.8 14348.9 14451.9 15035.6 15277.8 15298.8 1850.5 1858.1 1914.1 1928.8 1993.7 82174.6 82316.6 82898.2 84400.0 87594.6 16909.4 16974.2 17028.3 17264.4 18650.8 29478.0 29562.1 29593.0 29608.4 29674.0 8924.6 9189.3 9301.4 9507.5 9541.8 7774.7 7799.2 7931.8 8010.0 8185.4 8134.4 8241.0 8276.5 8457.2 8565.7 3271.9 3399.2 3422.4 3425.2 3518.9 782.7 816.7 827.8 854.3 910.4 16454.7 16576.1 17786.7 18045.0 18070.3 846.3 863.9 885.9 886.5 925.4 9375.0 9812.0 9823.8 9856.3 10229.9 359099.0 359439.0 359663.0 362821.0 367763.0 77828.0 77859.2 79033.6 80802.0 80880.6 866.2 880.3 881.2 887.3 892.2 15343.3 15401.3 15579.6 15649.5 16004.4 38435.0 38608.8 39108.4 39238.6 39517.8 48461.0 52633.0 52738.5 53600.7 54858.5 6822.7 7175.4 7206.7 7422.1 7578.7 9850.8 10866.3 11781.6 12007.3 12152.6 10537.3 10784.7 10785.4 10829.3 11260.7 10173.6 10178.8 10330.9 10643.1 10842.5 10020.5 10123.4 10513.3 10784.7 11056.6 6619.7 6682.5 6767.4 6770.6 7075.0 8591.5 10797.5 10853.8 10866.7 10905.1 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#3] mootools.html Cycles:180 Average:4931.97 Median:5017.30 stddev:2744.99 (54.7%) stddev-sans-first:2738.74 15:52:56 INFO - PROCESS | 8704 | Values: 1250.1 1351.1 1382.1 1447.7 1678.4 6291.5 7220.2 7508.2 7515.5 7690.3 7446.1 7499.6 7504.1 7510.3 7524.1 2193.8 2225.6 2226.6 2229.1 2229.4 2098.5 2132.7 2174.5 2175.2 2175.3 2136.2 2152.3 2162.7 2163.8 2177.9 2123.4 2125.5 2131.2 2131.3 2132.3 2043.6 2079.4 2080.6 2081.4 2101.0 7257.8 7332.4 7397.4 7407.6 7546.9 4255.3 4451.0 4464.5 4472.7 4481.0 2922.6 2966.5 3030.5 3034.8 3046.1 921.6 922.6 933.9 934.6 939.5 9859.0 9919.9 9922.8 9931.1 9951.8 3891.2 4034.9 4037.4 4039.8 4275.7 7990.5 8144.5 8156.4 8157.2 8187.0 4532.4 4535.1 4538.0 4543.2 4550.1 3555.8 3933.5 4130.0 4226.3 4232.3 3349.7 3376.0 3476.5 3531.9 3549.3 1014.8 1025.2 1033.3 1039.3 1041.5 785.7 811.0 812.0 812.4 818.4 4898.1 4925.7 4963.5 5041.3 5214.7 409.7 410.3 411.3 411.7 414.1 3186.6 3263.9 3264.0 3307.9 3411.6 8927.2 9293.7 9325.2 9333.1 9340.6 9270.8 9274.3 9290.2 9294.7 9295.7 7046.5 7153.9 7177.1 7182.4 7187.6 4814.4 4972.5 4993.3 5158.8 5195.5 8286.8 8496.7 8512.4 8602.2 8784.7 8560.4 8619.4 8738.3 8740.6 8832.7 6113.3 6640.4 7024.5 7195.5 7197.4 7037.6 7204.1 7286.9 7307.6 7436.9 6305.4 6342.7 6468.9 6577.0 6636.7 6436.2 6458.9 6495.9 6581.8 6630.5 6102.5 6257.7 6262.4 6271.9 6275.1 4400.8 4423.0 4487.4 4505.5 4566.3 4466.8 4494.6 4536.2 4551.1 4564.6 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#4] prototype.html Cycles:180 Average:4217.53 Median:4837.69 stddev:1210.24 (25.0%) stddev-sans-first:1177.11 15:52:56 INFO - PROCESS | 8704 | Values: 285.8 297.3 297.4 302.0 303.6 4866.1 4872.5 4894.9 4906.2 4913.2 4800.8 4813.0 4825.4 4836.2 4840.3 3627.9 3726.7 3746.3 3782.3 3814.5 3812.8 3830.3 3831.4 3831.7 3833.4 3767.3 3779.6 3798.7 3800.0 3824.2 3624.8 3660.8 3728.9 3774.9 3802.5 3356.2 3379.6 3381.4 3383.6 3400.2 4716.3 4724.0 4735.1 4739.3 4758.4 4275.6 4295.8 4324.9 4366.8 4377.8 4958.8 4979.0 4986.8 4999.3 5009.3 3693.9 3702.5 3704.1 3709.4 3714.2 5064.4 5255.3 5304.0 5308.9 5322.4 3779.8 3782.9 3784.9 3809.0 3815.5 3835.4 3839.5 3847.9 3860.9 3874.9 4334.0 4348.4 4360.0 4367.5 4369.8 4794.2 5009.3 5042.0 5055.8 5058.2 1301.5 1309.2 1311.2 1311.7 1314.5 867.5 878.7 896.2 897.2 909.7 4401.2 4411.2 4429.2 4435.0 4466.9 5142.7 5153.2 5155.3 5163.8 5171.3 4272.4 4396.5 4408.4 4415.0 4422.9 2704.6 2720.3 2749.8 2752.7 2758.7 4845.7 4855.8 4860.4 4860.7 4864.3 4994.7 5255.6 5284.6 5299.3 5316.3 4830.1 4839.2 4846.8 4847.0 4856.9 5127.8 5159.4 5177.6 5226.7 5227.8 4863.5 4996.9 5085.6 5114.4 5131.7 4920.8 4946.8 4950.5 4971.0 4981.5 3738.6 3748.3 3790.6 3790.9 3794.7 5115.5 5266.0 5319.0 5321.7 5323.8 5080.5 5080.8 5082.6 5082.9 5087.3 5083.3 5085.6 5135.8 5145.0 5148.8 4946.9 4987.4 5017.6 5093.2 5103.8 4886.6 4971.4 5081.6 5091.9 5124.9 5008.3 5020.4 5049.6 5065.4 5066.6 15:52:56 INFO - PROCESS | 8704 | 15:52:56 INFO - PROCESS | 8704 | [#5] yui.html Cycles:180 Average:17675.94 Median:2319.33 stddev:24944.43 (1075.5%) stddev-sans-first:24981.56 15:52:56 INFO - PROCESS | 8704 | Values: 626.6 705.6 708.0 722.3 730.5 12899.3 14156.0 14264.1 14271.6 14276.2 13614.4 13758.6 13766.4 13802.2 13806.5 618.9 753.8 757.9 777.1 780.3 777.4 781.1 781.1 781.4 781.8 806.0 808.4 808.6 808.9 809.0 649.6 797.6 813.2 818.8 822.6 597.5 597.5 598.8 598.9 599.2 13084.1 13118.1 13120.1 13129.3 13146.7 12446.4 12708.7 12884.2 13077.6 13274.9 11648.4 11878.3 11895.8 11970.6 12276.0 4044.9 4056.4 4081.1 4220.2 4253.1 66068.5 67757.3 69000.3 69147.7 69266.0 9625.1 9964.1 10203.8 10247.5 10322.7 18410.8 18670.3 18682.4 18709.3 18712.0 6397.2 6508.3 6518.3 6784.7 6864.6 3654.9 3655.0 3658.0 3683.8 3733.5 2739.3 2822.8 2837.7 2842.6 2846.6 418.5 497.3 500.7 501.6 506.4 454.1 547.8 621.7 628.6 633.5 7629.3 7789.3 7989.0 8009.4 8100.6 485.3 580.5 595.8 603.3 607.5 3926.7 3964.7 3965.5 3975.2 4067.0 75532.6 94582.0 98580.0 101760.0 104931.5 80812.6 80935.2 81582.2 81730.0 82987.2 70251.3 70627.0 70745.3 73492.0 73728.0 8025.6 8141.6 8190.2 8482.5 8524.7 36296.8 39258.2 43053.8 44031.6 47291.3 49119.3 61806.4 63459.4 63552.0 63569.0 10688.1 10846.3 11156.6 11247.4 11298.5 12947.9 13229.6 13250.7 13625.9 13709.2 11326.9 11472.1 11644.4 11892.6 11997.6 11436.9 11456.1 11634.6 11683.4 12288.1 10795.8 10804.9 11148.5 11364.9 11503.4 10070.2 10111.3 10335.5 10567.5 10649.7 10119.0 10336.8 10342.6 10347.0 10375.4 15:52:56 INFO - PROCESS | 8704 | -------- Summary: end -------- 15:52:56 INFO - PROCESS | 8704 | 15:52:57 INFO - TEST-INFO | 8704: exit 0 15:52:57 INFO - TEST-OK | dromaeo_css | took 1119742ms 15:52:57 INFO - TEST-START | kraken 15:52:57 INFO - operating with platform_type : linux_ 15:52:57 INFO - Initialising browser for kraken test... 15:52:57 INFO - TEST-INFO | started process 8891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnc_k8v/profile http://localhost:39535/getInfo.html) 15:53:01 INFO - PROCESS | 8891 | __metrics Screen width/height:1600/1200 15:53:01 INFO - PROCESS | 8891 | colorDepth:24 15:53:01 INFO - PROCESS | 8891 | Browser inner width/height: 1024/697 15:53:01 INFO - PROCESS | 8891 | __metrics 15:53:02 INFO - PROCESS | 8891 | 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" 15:53:02 INFO - PROCESS | 8891 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:53:03 INFO - TEST-INFO | 8891: exit 0 15:53:03 INFO - Browser initialized. 15:53:03 INFO - Running cycle 1/1 for kraken test... 15:53:03 INFO - TEST-INFO | started process 8968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnc_k8v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:15 INFO - PROCESS | 8968 | RSS: Main: 157253632 15:53:15 INFO - PROCESS | 8968 | 15:53:18 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39535/tests/kraken/driver.html?testName=audio-beat-detection) 15:53:19 INFO - PROCESS | 8968 | RSS: Main: 214085632 15:53:19 INFO - PROCESS | 8968 | 15:53:22 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39535/tests/kraken/driver.html?testName=audio-dft) 15:53:22 INFO - PROCESS | 8968 | RSS: Main: 191246336 15:53:22 INFO - PROCESS | 8968 | 15:53:25 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39535/tests/kraken/driver.html?testName=audio-fft) 15:53:26 INFO - PROCESS | 8968 | RSS: Main: 271806464 15:53:26 INFO - PROCESS | 8968 | 15:53:28 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39535/tests/kraken/driver.html?testName=audio-oscillator) 15:53:29 INFO - PROCESS | 8968 | RSS: Main: 180445184 15:53:29 INFO - PROCESS | 8968 | 15:53:31 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39535/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:53:32 INFO - PROCESS | 8968 | RSS: Main: 184885248 15:53:32 INFO - PROCESS | 8968 | 15:53:35 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39535/tests/kraken/driver.html?testName=imaging-darkroom) 15:53:35 INFO - PROCESS | 8968 | RSS: Main: 221282304 15:53:35 INFO - PROCESS | 8968 | 15:53:39 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39535/tests/kraken/driver.html?testName=imaging-desaturate) 15:53:40 INFO - PROCESS | 8968 | RSS: Main: 221224960 15:53:40 INFO - PROCESS | 8968 | 15:53:42 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39535/tests/kraken/driver.html?testName=json-parse-financial) 15:53:43 INFO - PROCESS | 8968 | RSS: Main: 220413952 15:53:43 INFO - PROCESS | 8968 | 15:53:45 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39535/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:53:45 INFO - PROCESS | 8968 | RSS: Main: 185139200 15:53:45 INFO - PROCESS | 8968 | 15:53:47 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:53:48 INFO - PROCESS | 8968 | RSS: Main: 190693376 15:53:48 INFO - PROCESS | 8968 | 15:53:50 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:53:51 INFO - PROCESS | 8968 | RSS: Main: 194859008 15:53:51 INFO - PROCESS | 8968 | 15:53:53 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:53:54 INFO - PROCESS | 8968 | RSS: Main: 190308352 15:53:54 INFO - PROCESS | 8968 | 15:53:57 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:53:57 INFO - PROCESS | 8968 | RSS: Main: 193765376 15:53:57 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | Cycle 1(1): loaded http://localhost:39535/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39535/tests/kraken/driver.html?testName=ai-astar) 15:53:59 INFO - PROCESS | 8968 | RSS: Main: 244559872 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | __start_tp_report 15:53:59 INFO - PROCESS | 8968 | _x_x_mozilla_page_load 15:53:59 INFO - PROCESS | 8968 | _x_x_mozilla_page_load_details 15:53:59 INFO - PROCESS | 8968 | |i|pagename|runs| 15:53:59 INFO - PROCESS | 8968 | |0;ai-astar;104;114;114;114;114;114;114;114;112;115 15:53:59 INFO - PROCESS | 8968 | |1;audio-beat-detection;153;120;124;172;135;127;154;123;180;125 15:53:59 INFO - PROCESS | 8968 | |2;audio-dft;161;161;163;160;163;161;161;160;160;160 15:53:59 INFO - PROCESS | 8968 | |3;audio-fft;77;78;77;121;76;75;75;75;115;75 15:53:59 INFO - PROCESS | 8968 | |4;audio-oscillator;86;85;120;86;85;87;116;84;86;127 15:53:59 INFO - PROCESS | 8968 | |5;imaging-gaussian-blur;118;117;116;117;119;117;117;117;118;117 15:53:59 INFO - PROCESS | 8968 | |6;imaging-darkroom;146;146;146;146;146;146;146;145;146;145 15:53:59 INFO - PROCESS | 8968 | |7;imaging-desaturate;94;96;95;95;95;95;95;95;95;95 15:53:59 INFO - PROCESS | 8968 | |8;json-parse-financial;64;62;61;61;60;62;61;63;63;62 15:53:59 INFO - PROCESS | 8968 | |9;json-stringify-tinderbox;65;65;65;66;65;65;64;66;66;67 15:53:59 INFO - PROCESS | 8968 | |10;stanford-crypto-aes;80;79;79;79;79;79;79;80;80;83 15:53:59 INFO - PROCESS | 8968 | |11;stanford-crypto-ccm;136;132;144;130;131;131;129;126;172;130 15:53:59 INFO - PROCESS | 8968 | |12;stanford-crypto-pbkdf2;175;166;167;165;165;167;169;167;169;164 15:53:59 INFO - PROCESS | 8968 | |13;stanford-crypto-sha256-iterative;67;67;67;68;69;67;67;66;67;65 15:53:59 INFO - PROCESS | 8968 | __end_tp_report 15:53:59 INFO - PROCESS | 8968 | __start_cc_report 15:53:59 INFO - PROCESS | 8968 | _x_x_mozilla_cycle_collect,1937 15:53:59 INFO - PROCESS | 8968 | __end_cc_report 15:53:59 INFO - PROCESS | 8968 | __startTimestamp1470092039945__endTimestamp 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | ------- Summary: start ------- 15:53:59 INFO - PROCESS | 8968 | Number of tests: 14 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.21 (2.8%) stddev-sans-first:0.78 15:53:59 INFO - PROCESS | 8968 | Values: 104.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 112.0 115.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:144.00 stddev:21.95 (15.2%) stddev-sans-first:22.87 15:53:59 INFO - PROCESS | 8968 | Values: 153.0 120.0 124.0 172.0 135.0 127.0 154.0 123.0 180.0 125.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 15:53:59 INFO - PROCESS | 8968 | Values: 161.0 161.0 163.0 160.0 163.0 161.0 161.0 160.0 160.0 160.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#3] audio-fft Cycles:10 Average:84.40 Median:75.50 stddev:17.80 (23.6%) stddev-sans-first:18.67 15:53:59 INFO - PROCESS | 8968 | Values: 77.0 78.0 77.0 121.0 76.0 75.0 75.0 75.0 115.0 75.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:85.50 stddev:17.33 (20.3%) stddev-sans-first:17.99 15:53:59 INFO - PROCESS | 8968 | Values: 86.0 85.0 120.0 86.0 85.0 87.0 116.0 84.0 86.0 127.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 15:53:59 INFO - PROCESS | 8968 | Values: 118.0 117.0 116.0 117.0 119.0 117.0 117.0 117.0 118.0 117.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 15:53:59 INFO - PROCESS | 8968 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 15:53:59 INFO - PROCESS | 8968 | Values: 94.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:1.20 (1.9%) stddev-sans-first:1.00 15:53:59 INFO - PROCESS | 8968 | Values: 64.0 62.0 61.0 61.0 60.0 62.0 61.0 63.0 63.0 62.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 15:53:59 INFO - PROCESS | 8968 | Values: 65.0 65.0 65.0 66.0 65.0 65.0 64.0 66.0 66.0 67.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:79.50 stddev:1.25 (1.6%) stddev-sans-first:1.32 15:53:59 INFO - PROCESS | 8968 | Values: 80.0 79.0 79.0 79.0 79.0 79.0 79.0 80.0 80.0 83.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:131.50 stddev:13.53 (10.3%) stddev-sans-first:14.35 15:53:59 INFO - PROCESS | 8968 | Values: 136.0 132.0 144.0 130.0 131.0 131.0 129.0 126.0 172.0 130.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.00 stddev:3.13 (1.9%) stddev-sans-first:1.74 15:53:59 INFO - PROCESS | 8968 | Values: 175.0 166.0 167.0 165.0 165.0 167.0 169.0 167.0 169.0 164.0 15:53:59 INFO - PROCESS | 8968 | 15:53:59 INFO - PROCESS | 8968 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.05 (1.6%) stddev-sans-first:1.12 15:53:59 INFO - PROCESS | 8968 | Values: 67.0 67.0 67.0 68.0 69.0 67.0 67.0 66.0 67.0 65.0 15:53:59 INFO - PROCESS | 8968 | -------- Summary: end -------- 15:53:59 INFO - PROCESS | 8968 | 15:54:00 INFO - TEST-INFO | 8968: exit 0 15:54:01 INFO - TEST-OK | kraken | took 63433ms 15:54:01 INFO - SUITE-END | took 1183s 15:54:01 INFO - Completed test suite (00:19:43) 15:54:01 INFO - javascript benchmark 15:54:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2627.133046094789, 2629.9079532216374, 2641.6829980402354, 2663.5205662980657, 2663.773579245764, 4623.375844261032, 4737.455192652846, 4742.525747425256, 4778.689385189965, 4808.807647694092, 4700.6474514411475, 4741.359916411284, 4743.098811225865, 4747.8338258160975, 4813.4223893132785, 4294.613484786369, 4304.569543045702, 4359.847405340814, 4410.2282100632265, 4484.66365022623, 4404.163208990298, 4424.380586717846, 4463.598276155145, 4477.619402350804, 4481.282994720846, 4226.154769046172, 4304.634106100985, 4357.607815296638, 4365.9126817463475, 4451.688381813275, 4283.828646854122, 4298.634616057639, 4316.82732690692, 4343.04453020335, 4366.519682834885, 4284.957150428487, 4342.717723347972, 4350.7172033818015, 4355.73865568067, 4391.494978077498, 4615.376924115235, 4729.385179926588, 4793.2810078488155, 4819.590334821544, 4841.15279826029, 4960.553550180465, 5021.39743230811, 5060.21566657165, 5070.3915530136255, 5072.67027643202, 5140.665856719302, 5146.485351464861, 5149.356330458692, 5179.559737422286, 5183.222516622499, 4617.861464156013, 4719.244920812654, 4778.426588809365, 4789.808407663654, 4830.203016502238, 5217.217417387422, 5267.578593712494, 5280.973595132, 5283.286756287852, 5291.1798671206025, 5015.448300686921, 5018.799248030038, 5058.140116180185, 5070.416902056237, 5070.898582028339, 5102.158143906214, 5126.53861152498, 5157.277981082651, 5160.92258616121, 5162.586993040548, 4889.095517329305, 5005.874853128701, 5019.272205530251, 5047.621428392885, 5105.591552675777, 5034.798608055637, 5038.395392552917, 5051.393832740095, 5051.898962020739, 5082.110630639623, 4351.042770590465, 4363.847265345697, 4373.147236288957, 4375.212461756914, 4417.359482874966, 3692.316921369555, 3731.776093434403, 3755.793431361247, 3796.981015094962, 3845.288621605012, 4371.169477799272, 4384.802683879233, 4386.670999675037, 4427.911441771146, 4431.7562534060935, 5129.9230511542355, 5131.512506311894, 5136.768845401966, 5136.794528218829, 5176.818811341585, 4242.893927651833, 4315.546867578922, 4350.0, 4366.257736184856, 4397.494288156903, 4565.315202719552, 4573.131105090022, 4592.954070459253, 4629.051044535862, 4637.118947399983, 4754.096721622881, 4757.476677565534, 4763.118823017822, 4777.3072904429355, 4779.06808172403, 4896.951030489649, 5086.237064440364, 5086.720230387365, 5112.335396398445, 5120.846374608767, 4822.156122678587, 4849.709017458964, 4856.077345304381, 4877.219644856806, 4902.240152776325, 5058.671186372874, 5065.39215294167, 5068.366454193226, 5071.290019397361, 5071.797128114834, 4927.531884470969, 5043.445221025758, 5056.64603477753, 5088.669236499616, 5180.326557547494, 4940.654154209171, 4941.0, 4941.530554597308, 4944.851654450372, 4958.082754690394, 4958.256261560795, 4966.255061740768, 4967.130752118293, 4968.776405061709, 4996.075725990703, 4729.314249433744, 4791.592714619296, 4837.419509658864, 4851.102546028855, 4932.679375840559, 4959.380077490314, 4962.652614316963, 4966.602671786321, 4968.27959945813, 4981.0, 4865.756712164449, 4871.585915197248, 4873.098476781806, 4873.975630121827, 4918.704877707349], "value": 4671.253252893703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.700382299293, 571.0891128649805, 574.2448679985815, 583.3087790967702, 597.2057163971915, 29201.269968250803, 39474.0, 40888.18223635526, 41094.58905410945, 44368.778156109176, 41217.58782412175, 41760.58239417605, 41780.16439671205, 41789.164216715726, 41805.58194418055, 7431.29402706742, 7526.962365188167, 7557.05536807899, 7563.130240022385, 7569.432292578051, 7598.354139898088, 7665.5783931883725, 7699.384049276045, 7711.958885550463, 7717.2668596483245, 7741.148473667892, 7765.922340776605, 7800.258975397328, 7812.843743125134, 7816.49192802466, 7733.961330193341, 7757.806054848617, 7767.339776119008, 7768.65041073153, 7769.1842356552315, 7641.541507509512, 7653.846923061507, 7697.615119244071, 7714.652840622185, 7716.807079822993, 38799.61200387988, 39238.411423828664, 39303.60696393028, 39363.0, 39396.212075758325, 2956.9852150739325, 3069.539569064636, 3295.2915123248376, 3626.691731202851, 3744.925101497982, 27358.452830943468, 29371.265718356997, 29766.851165744032, 30025.099247022623, 30209.395812083854, 3484.756067075306, 3531.6644918732677, 3548.432250839853, 3563.572371315433, 3623.655752703515, 257293.0, 257382.0, 258959.0, 259799.0, 259919.0, 54462.0, 54849.45150548523, 54883.17675234874, 54934.72532637391, 54958.0, 32272.515912261333, 33315.0, 33454.16364590856, 57948.420515795144, 58182.0, 14484.565463036126, 14645.780313295309, 14673.486427974969, 14739.484118055816, 14777.704445911237, 10485.0, 10620.681379558626, 10621.362718236931, 10623.150147988143, 10623.575056997788, 17815.910920445316, 17936.9103154486, 18027.549311267325, 18074.728879066824, 18081.186346614435, 6860.622665753332, 6898.550434969517, 6967.442604591621, 7045.089975063889, 7057.435405167575, 790.6442101054548, 887.3655336434481, 920.852663573825, 993.5380048277585, 1024.2522958240527, 26950.460990780073, 28179.0, 28758.0, 29036.41927161445, 29094.41811163765, 925.8287216864901, 926.2080920812742, 930.1721467893581, 930.4882314726873, 966.7341481092615, 14430.567082987318, 15021.098734075771, 15165.393384264726, 15225.31486083129, 15332.770008449883, 372334.1383293066, 376533.0, 377390.0, 377418.0, 377569.0, 49886.50113498818, 50563.49436505588, 50613.24080138801, 50651.0, 50705.746471267405, 1072.6406653771073, 1152.665726939195, 1197.21582363552, 1211.2127117373602, 1228.5270170984236, 28219.71780282171, 29319.560206597343, 29643.0, 30334.84832575823, 30387.2403189922, 42133.78933105315, 42684.0, 42695.786521067195, 42777.358339625556, 42844.571554284055, 285950.0, 288061.0, 288281.0, 289167.0, 290629.0, 24741.752582474666, 25452.363690907874, 25841.741582583934, 25870.353241169123, 26890.0, 41552.58447415487, 41607.0, 41656.79171604244, 42333.36499952441, 43147.0, 35470.46794298139, 35883.28233435265, 36073.27853442864, 36409.45385819162, 36786.0, 35629.64370356263, 35738.463923040654, 36274.455883161245, 36397.45403818892, 36479.635203647624, 35202.47196292006, 36374.0, 36759.26481470409, 38631.227375452894, 39302.80348598239, 30764.69235307618, 32847.83576082104, 33438.83280583582, 33499.665003349655, 33792.83103584466, 25153.119640812485, 25298.620520692573, 25379.238622841345, 25714.485710286062, 26043.348916277246], "value": 17483.91753411829, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.5771727623194, 370.10065540736, 372.12179256953584, 373.55920014383025, 388.49883650091385, 12513.311767852776, 16480.09359485228, 16891.239894204777, 17921.10394480274, 19121.904390478026, 17129.657406851857, 17171.0, 17658.470245892644, 17822.554436139104, 17832.1975511102, 4981.227909674007, 5128.282040514331, 5176.430592634807, 5208.401033881095, 5209.739513024334, 3738.532683414573, 5083.085044691954, 5271.630985830993, 5271.683698978054, 5729.5702822288495, 5940.257467816523, 5984.581079324449, 5985.0, 5988.700564971756, 5997.310309314419, 5825.582383310942, 5975.910361344583, 6074.088886666991, 6076.513878889678, 6109.389061093855, 5390.865228369283, 5399.352077750695, 5403.7027963462, 5403.810866619689, 5408.972955135238, 20780.688289675665, 20949.790502094937, 20998.475038124016, 21038.5792284155, 21448.463788405257, 1956.7064940258933, 2050.395133435625, 2067.0, 2069.5343547701796, 2585.8060645451665, 14348.92825535876, 14451.855481445262, 15035.624109397244, 15277.77777777787, 15298.847011529853, 1850.463365623981, 1858.1359667754423, 1914.119505027675, 1928.8360489358422, 1993.7308463357406, 82174.58912705458, 82316.58841705753, 82898.17101828965, 84400.0, 87594.5620271901, 16909.393607607257, 16974.236159372856, 17028.31886724542, 17264.395746148824, 18650.813491865178, 29478.0, 29562.113136605938, 29593.0, 29608.407831843244, 29674.0, 8924.553772311489, 9189.264858811279, 9301.395409298373, 9507.524623768784, 9541.809163816824, 7774.689012439552, 7799.181085986003, 7931.762047138595, 8009.959950200328, 8185.386096042702, 8134.430589858653, 8240.981777612495, 8276.503409795432, 8457.196566326189, 8565.743027709179, 3271.9018429447156, 3399.1502124468884, 3422.3668621305137, 3425.160835595295, 3518.894433167009, 782.7495201535454, 816.6610856494607, 827.7889138269703, 854.273867212864, 910.3763921713647, 16454.734484964934, 16576.07747991004, 17786.733199002025, 18045.0, 18070.277188912558, 846.2974486621033, 863.926566241864, 885.9069797671281, 886.534569351083, 925.4308600210957, 9374.95312523433, 9812.0, 9823.803523929482, 9856.31005829585, 10229.948850255701, 359099.0, 359439.0, 359663.0, 362821.0, 367763.0, 77828.0, 77859.2214077852, 79033.60483197548, 80802.0, 80880.59559702165, 866.1771317248639, 880.2693764175718, 881.18930583863, 887.2768693514765, 892.205936716323, 15343.309551070452, 15401.306941187897, 15579.610509737347, 15649.452269170524, 16004.359825606845, 38435.0, 38608.80695596504, 39108.413373798845, 39238.607613924636, 39517.80241098776, 48461.0, 52633.0, 52738.47261527489, 53600.73199633977, 54858.45141548693, 6822.692978815866, 7175.425965922818, 7206.747763828241, 7422.146453157956, 7578.734744283924, 9850.753731156778, 10866.293690910066, 11781.587644432404, 12007.279563226235, 12152.57465988686, 10537.262391632512, 10784.730381740519, 10785.352878827296, 10829.29609575375, 11260.718482038015, 10173.593056277667, 10178.847317290394, 10330.896691032993, 10643.095336896451, 10842.457877106272, 10020.498975051072, 10123.392596444237, 10513.264071515227, 10784.676459706221, 11056.557737690724, 6619.669016549057, 6682.465402767864, 6767.35710107539, 6770.593764374154, 7075.0, 8591.484510929364, 10797.460126993776, 10853.782924341627, 10866.728331791452, 10905.073068788925], "value": 9167.086075296167, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.112420181671, 1351.1420248142426, 1382.0532934939563, 1447.7176950494652, 1678.3622223555046, 6291.528135389847, 7220.24187460317, 7508.249175082489, 7515.4739168258175, 7690.346320562745, 7446.106467223941, 7499.625018749054, 7504.137024242214, 7510.286522780327, 7524.134724506684, 2193.7806219378053, 2225.5660146271484, 2226.5880812049822, 2229.0972156276653, 2229.4092065602626, 2098.5488120054183, 2132.6694362373855, 2174.499865031044, 2175.2169219081197, 2175.3473957812594, 2136.241634219853, 2152.2789865395034, 2162.7296587926508, 2163.8485306028583, 2177.8911054447294, 2123.4054464749893, 2125.5430082532253, 2131.2221039320534, 2131.3073251193346, 2132.349633361824, 2043.6117137743781, 2079.4073688998715, 2080.552681173555, 2081.42760740796, 2100.978990210094, 7257.782266532013, 7332.376747976428, 7397.371223446014, 7407.555546667218, 7546.924530754677, 4255.319148936186, 4451.043025749447, 4464.486584042812, 4472.731636101845, 4480.955190448087, 2922.576226447153, 2966.5253559430603, 3030.499967505359, 3034.757219422441, 3046.1166261784056, 921.5576523268802, 922.6263363337791, 933.929955253352, 934.5794392523392, 939.5349302095439, 9858.9507052465, 9919.90080099197, 9922.801543969152, 9931.106200441995, 9951.75120621983, 3891.2412079644764, 4034.8587799427464, 4037.414574886625, 4039.7980100994832, 4275.679324050709, 7990.480618759762, 8144.511329320259, 8156.388270879589, 8157.2250636189465, 8186.959065204755, 4532.4334458192725, 4535.115652447741, 4538.0, 4543.159515489645, 4550.13547425988, 3555.768875023115, 3933.547642021147, 4130.0294430808735, 4226.323788193874, 4232.25935461293, 3349.6650334966307, 3376.020953923384, 3476.4959080933627, 3531.858725650945, 3549.254656522159, 1014.7716763728102, 1025.220832167558, 1033.2715435617872, 1039.2569312941284, 1041.5156952017348, 785.7171418289412, 810.9756707298791, 812.0174588747564, 812.3785304242259, 818.3698552114957, 4898.069366820364, 4925.729084900365, 4963.528464795839, 5041.269015992661, 5214.6610470320065, 409.6968243499789, 410.26152924735356, 411.29052384636265, 411.7262020756223, 414.1179288116293, 3186.601674790651, 3263.918402039968, 3264.0, 3307.8676852925614, 3411.6076651184935, 8927.151920567536, 9293.674721384854, 9325.207357374564, 9333.113354231336, 9340.57967391469, 9270.768230794283, 9274.30442716799, 9290.210332121711, 9294.674686385944, 9295.67465138717, 7046.5067445279365, 7153.856922861513, 7177.138743350832, 7182.42540596751, 7187.5687458752645, 4814.374131362901, 4972.502749725143, 4993.325901003318, 5158.767855446589, 5195.506426889439, 8286.792830179053, 8496.70261540843, 8512.404131710968, 8602.18279263469, 8784.736457906272, 8560.357973152162, 8619.353548484014, 8738.300935925237, 8740.562971851508, 8832.690855820272, 6113.327533971348, 6640.369164929324, 7024.5082844200415, 7195.496315257882, 7197.388222001188, 7037.577745335297, 7204.135503739585, 7286.854262914818, 7307.561546307238, 7436.888446673413, 6305.432511074026, 6342.673367233953, 6468.902966455413, 6576.967115164393, 6636.734530618721, 6436.163298771317, 6458.935410645833, 6495.870082598227, 6581.769638062645, 6630.53586248958, 6102.4812890902995, 6257.68711564429, 6262.405071518198, 6271.874562508633, 6275.058741188858, 4400.779961001873, 4423.0, 4487.41663583732, 4505.504394516666, 4566.337881007302, 4466.754328511833, 4494.617957473652, 4536.160810250114, 4551.08978204367, 4564.612007979226], "value": 3919.2533672018103, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.8323747491497, 297.317655979527, 297.3933176320307, 301.9788614796964, 303.62047440699126, 4866.075445665326, 4872.512748725126, 4894.877628059291, 4906.19047857104, 4913.164761990462, 4800.807967681298, 4812.9518704812945, 4825.445073816503, 4836.226203807391, 4840.273958906157, 3627.8548858045647, 3726.7204959628, 3746.250749850027, 3782.300274449222, 3814.4850445189822, 3812.752171108869, 3830.291396091732, 3831.4291140632345, 3831.67430768385, 3833.367494363427, 3767.321882061228, 3779.622037796226, 3798.65812076913, 3800.0239875264847, 3824.2160357126722, 3624.800635965021, 3660.7803531788045, 3728.850845966158, 3774.9056273593105, 3802.505674262356, 3356.194513316811, 3379.611344695367, 3381.3913495570787, 3383.6278009418943, 3400.2179498715186, 4716.268978308368, 4723.95276047242, 4735.124002059595, 4739.265413860822, 4758.381410416659, 4275.615194632467, 4295.8496452624, 4324.935125973113, 4366.8253269869165, 4377.824887004516, 4958.752062396866, 4978.9751051244875, 4986.825461108844, 4999.275105109775, 5009.298698182258, 3693.907652308687, 3702.5186725725753, 3704.073981504624, 3709.3508635988865, 3714.182879766474, 5064.366954130734, 5255.343082114736, 5303.992241474146, 5308.867278318035, 5322.414534401213, 3779.7526816150603, 3782.9432558511644, 3784.9243015140096, 3808.980955095207, 3815.523059617548, 3835.4055121456217, 3839.520059992501, 3847.9038024049623, 3860.9227815444096, 3874.903127421837, 4333.978330108329, 4348.369486424451, 4359.978200108979, 4367.475902891673, 4369.781510924441, 4794.232922732346, 5009.323741294951, 5042.0084066793415, 5055.823046193365, 5058.165402708585, 1301.531448678475, 1309.214471317221, 1311.1608570514877, 1311.6917524381763, 1314.5399110311314, 867.4775768526196, 878.7056336127469, 896.2471523919938, 897.2059727141396, 909.667971190524, 4401.207782599099, 4411.18393097278, 4429.247028005247, 4434.977825110854, 4466.888327791831, 5142.691438513626, 5153.227015947563, 5155.252488389163, 5163.845084647466, 5171.319321220619, 4272.4445822042935, 4396.516383197845, 4408.4269045024585, 4415.0, 4422.872167597242, 2704.60642331538, 2720.265528307346, 2749.752522273005, 2752.6972033076345, 2758.6689597248055, 4845.6608037437745, 4855.786053486629, 4860.416749989953, 4860.708357498562, 4864.270359446112, 4994.650374473825, 5255.5795536357, 5284.550813180846, 5299.284596579412, 5316.282301889196, 4830.058138662997, 4839.1531481991215, 4846.80612775485, 4846.95153048465, 4856.854294371104, 5127.846304552022, 5159.432462429055, 5177.559907407913, 5226.686398816083, 5227.843164705065, 4863.537963893425, 4996.850724333496, 5085.644004919768, 5114.360704911886, 5131.690739167464, 4920.803167873388, 4946.826861059845, 4950.504949505164, 4970.975145124396, 4981.4769449207315, 3738.607446218217, 3748.2503499299705, 3790.6209379061875, 3790.938537209586, 3794.734368594172, 5115.514026167508, 5265.973670131625, 5319.042572337016, 5321.7339133042415, 5323.787048518016, 5080.517350851663, 5080.847574572768, 5082.593392528515, 5082.923756143587, 5087.3132127163835, 5083.339165908408, 5085.618578606545, 5135.815794207466, 5144.9742751286, 5148.794048238028, 4946.851594452172, 4987.351644286219, 5017.573506251863, 5093.236014597692, 5103.8468845934685, 4886.633502487257, 4971.37857767779, 5081.644284900021, 5091.87270318245, 5124.923126153185, 5008.273800299009, 5020.447750747342, 5049.57078648319, 5065.442801291929, 5066.645334826527], "value": 3833.098258689172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.5582764151274, 705.5555000349779, 707.961062141582, 722.2777222777223, 730.4959577891258, 12899.29053902035, 14156.0, 14264.0728352657, 14271.643208919782, 14276.214808185545, 13614.387352569134, 13758.587242382719, 13766.38051287692, 13802.171869687802, 13806.516771912986, 618.9257289125288, 753.7512620835112, 757.9469437139403, 777.0985656638301, 780.2977320411646, 777.4324742937658, 781.1290411191537, 781.1446466119619, 781.3592853859838, 781.7654703588929, 806.0408114343941, 808.4300568099505, 808.5795386399069, 808.8948436703221, 809.0291650019971, 649.6069877723957, 797.6011994003028, 813.1624426840364, 818.8034871630825, 822.6462621072936, 597.5010865926956, 597.5309382135047, 598.769473752604, 598.8892054969845, 599.1701493431628, 13084.149530280501, 13118.147320424141, 13120.147190432592, 13129.343532823415, 13146.737065258641, 12446.377681115908, 12708.682282942907, 12884.226946383247, 13077.607671769862, 13274.86725132746, 11648.417579121011, 11878.346690931996, 11895.821562676567, 11970.640880773592, 12276.0, 4044.8786536403954, 4056.391541268804, 4081.0613558881623, 4220.198162349144, 4253.064325848309, 66068.49349286809, 67757.32242677608, 69000.30999689989, 69147.65426172837, 69266.0, 9625.133737963477, 9964.10323070927, 10203.795924081476, 10247.538860751283, 10322.741931451774, 18410.81589184118, 18670.253189872525, 18682.439526814218, 18709.345172919155, 18712.0, 6397.232332120176, 6508.349165083454, 6518.315576864456, 6784.660766961731, 6864.622445765531, 3654.8903532894055, 3654.9817250913575, 3658.0, 3683.7605555639334, 3733.533308336458, 2739.315171207198, 2822.7741780657498, 2837.744602985741, 2842.5878247653973, 2846.6014757933904, 418.4685449479205, 497.3310896843739, 500.70458429526025, 501.6388200495637, 506.43279526929336, 454.1054123376942, 547.7808876449373, 621.6798348850346, 628.6385328436168, 633.4773811605444, 7629.2752188542, 7789.261154645802, 7988.960055199686, 8009.4393392463135, 8100.635471403801, 485.2963203355149, 580.5065694160014, 595.8361450601119, 603.3407384214723, 607.4563265877015, 3926.7447615904875, 3964.731285988456, 3965.5439624442984, 3975.2447035063246, 4066.9796651017145, 75532.62233688796, 94582.0, 98580.0, 101760.0, 104931.4753426228, 80812.59593701994, 80935.19064809394, 81582.18417815864, 81730.0, 82987.17012829916, 70251.2974870255, 70627.0, 70745.29254707387, 73492.0, 73728.0, 8025.638846252049, 8141.592920354077, 8190.180981901762, 8482.491050536988, 8524.659013639384, 36296.81851590725, 39258.21483570256, 43053.78473107614, 44031.559684402746, 47291.290630641255, 49119.26321105258, 61806.38193618006, 63459.36540634534, 63552.046719300175, 63569.0, 10688.091512221548, 10846.349219046882, 11156.553737850396, 11247.381394023407, 11298.548058077586, 12947.935260323638, 13229.603111906657, 13250.734985300047, 13625.93187034098, 13709.245991470565, 11326.886731132583, 11472.139589530652, 11644.41777911118, 11892.643220703392, 11997.580084696994, 11436.94281528587, 11456.083513319081, 11634.592789252336, 11683.357415342238, 12288.1398302118, 10795.784084318428, 10804.891951080388, 11148.498317575566, 11364.94317528407, 11503.424828758361, 10070.244731645302, 10111.342762720695, 10335.534900929326, 10567.471626418803, 10649.680509584725, 10119.0, 10336.793264134525, 10342.586296548054, 10347.0, 10375.429351385746], "value": 6591.135210331438, "unit": "score"}], "name": "dromaeo_css", "value": 6481.542228370272, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 112.0, 115.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 124.0, 172.0, 135.0, 127.0, 154.0, 123.0, 180.0, 125.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 160.0, 163.0, 161.0, 161.0, 160.0, 160.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 121.0, 76.0, 75.0, 75.0, 75.0, 115.0, 75.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 120.0, 86.0, 85.0, 87.0, 116.0, 84.0, 86.0, 127.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 119.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 61.0, 60.0, 62.0, 61.0, 63.0, 63.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0, 66.0, 66.0, 67.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 80.0, 80.0, 83.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 132.0, 144.0, 130.0, 131.0, 131.0, 129.0, 126.0, 172.0, 130.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 166.0, 167.0, 165.0, 165.0, 167.0, 169.0, 167.0, 169.0, 164.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 69.0, 67.0, 67.0, 66.0, 67.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1531.4, "alertThreshold": 2.0}]} 15:54:01 INFO - Return code: 0 15:54:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:54:01 INFO - # TBPL SUCCESS # 15:54:01 INFO - Running post-action listener: _resource_record_post_action 15:54:01 INFO - [mozharness: 2016-08-01 22:54:01.258296Z] Finished run-tests step (success) 15:54:01 INFO - Running post-run listener: _resource_record_post_run 15:54:01 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 525729792; Read time: 8; Write time: 513196 15:54:01 INFO - TinderboxPrint: CPU usage
13.0% 15:54:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:54:01 INFO - TinderboxPrint: I/O write bytes / time
525,729,792 / 513,196 15:54:01 INFO - TinderboxPrint: CPU idle
8,327.5 (87.0%) 15:54:01 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 15:54:01 INFO - TinderboxPrint: Swap in / out
0 / 0 15:54:01 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 240750592; Read time: 0; Write time: 241044 15:54:01 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 284979200; Read time: 0; Write time: 272152 15:54:01 INFO - Running post-run listener: _upload_blobber_files 15:54:01 INFO - Blob upload gear active. 15:54:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:54:01 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 15:54:01 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'] 15:54:01 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 15:54:02 INFO - (blobuploader) - INFO - Open directory for files ... 15:54:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:54:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:54:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:03 INFO - (blobuploader) - INFO - Done attempting. 15:54:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:54:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:54:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:07 INFO - (blobuploader) - INFO - Done attempting. 15:54:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:54:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:54:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:08 INFO - (blobuploader) - INFO - Done attempting. 15:54:08 INFO - (blobuploader) - INFO - Iteration through files over. 15:54:08 INFO - Return code: 0 15:54:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:54:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:54:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa63230b3b6b893106a7c7507ea57ef3ffddb759cf22dd40df60667a76e2660aa836a9dbb5759b6a8853124e4c3fed4214b110795b00808f27628cb88499cc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e4a011661d44e359fceb6e0278bb17d81dc63f35f989e19e3073489bdef9a6bd9bbea8cf3779a0674b31530709998bd450c1629806b1c6817590e6dab81c5a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1aa060eac3a3dd194678523572dcd2bd731d619fff54ed979af623999a5857af8008c879b5d87f5827bb4731331a52d9e3cf665ddd8428ce12774b874cc6678f"} 15:54:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:54:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:54:08 INFO - Contents: 15:54:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa63230b3b6b893106a7c7507ea57ef3ffddb759cf22dd40df60667a76e2660aa836a9dbb5759b6a8853124e4c3fed4214b110795b00808f27628cb88499cc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e4a011661d44e359fceb6e0278bb17d81dc63f35f989e19e3073489bdef9a6bd9bbea8cf3779a0674b31530709998bd450c1629806b1c6817590e6dab81c5a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1aa060eac3a3dd194678523572dcd2bd731d619fff54ed979af623999a5857af8008c879b5d87f5827bb4731331a52d9e3cf665ddd8428ce12774b874cc6678f"} 15:54:08 INFO - Running post-run listener: copy_logs_to_upload_dir 15:54:08 INFO - Copying logs to upload dir... 15:54:08 INFO - mkdir: /builds/slave/test/build/upload/logs 15:54:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.787543 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-01 15:54:08.150331) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 15:54:08.153619) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 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/8fa63230b3b6b893106a7c7507ea57ef3ffddb759cf22dd40df60667a76e2660aa836a9dbb5759b6a8853124e4c3fed4214b110795b00808f27628cb88499cc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e4a011661d44e359fceb6e0278bb17d81dc63f35f989e19e3073489bdef9a6bd9bbea8cf3779a0674b31530709998bd450c1629806b1c6817590e6dab81c5a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1aa060eac3a3dd194678523572dcd2bd731d619fff54ed979af623999a5857af8008c879b5d87f5827bb4731331a52d9e3cf665ddd8428ce12774b874cc6678f"} build_url:https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011370 build_url: 'https://queue.taskcluster.net/v1/task/FRhkElOBSQeihP_JSsSw2A/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/8fa63230b3b6b893106a7c7507ea57ef3ffddb759cf22dd40df60667a76e2660aa836a9dbb5759b6a8853124e4c3fed4214b110795b00808f27628cb88499cc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e4a011661d44e359fceb6e0278bb17d81dc63f35f989e19e3073489bdef9a6bd9bbea8cf3779a0674b31530709998bd450c1629806b1c6817590e6dab81c5a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1aa060eac3a3dd194678523572dcd2bd731d619fff54ed979af623999a5857af8008c879b5d87f5827bb4731331a52d9e3cf665ddd8428ce12774b874cc6678f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 15:54:08.183015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:54:08.183778) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1470090808.614641-697331377 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 15:54:08.265593) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 15:54:08.266194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 15:54:08.266842) ========= ========= Total master_lag: 0.20 =========