builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1469599871.11 results: success (0) buildid: 20160726200813 builduid: e8052f853a4543a59073aa6aaa3f543b revision: 46de97426f7b5467a37e1512ed022f2908d8626a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.114807) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.115255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.115523) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.133850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.134122) ========= 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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 23:11:11-- 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% 42.6M=0s 2016-07-26 23:11:11 (42.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139657 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.287374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.287678) ========= 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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013593 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:11.336448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 23:11:11.336819) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 46de97426f7b5467a37e1512ed022f2908d8626a --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 46de97426f7b5467a37e1512ed022f2908d8626a --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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 23:11:12,032 truncating revision to first 12 chars 2016-07-26 23:11:12,032 Setting DEBUG logging. 2016-07-26 23:11:12,032 attempt 1/10 2016-07-26 23:11:12,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/46de97426f7b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 23:11:13,361 unpacking tar archive at: autoland-46de97426f7b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.665979 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 23:11:13.016599) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:13.016942) ========= script_repo_revision: 46de97426f7b5467a37e1512ed022f2908d8626a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:13.017339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:13.017773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 23:11:13.026313) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-26 23:11:13.026578) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:11:13 INFO - MultiFileLogger online at 20160726 23:11:13 in /builds/slave/test 23:11:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 23:11:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:11:13 INFO - {'append_to_log': False, 23:11:13 INFO - 'base_work_dir': '/builds/slave/test', 23:11:13 INFO - 'blob_upload_branch': 'Autoland', 23:11:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:11:13 INFO - 'branch': 'Autoland', 23:11:13 INFO - 'buildbot_json_path': 'buildprops.json', 23:11:13 INFO - 'config_files': ('talos/linux_config.py',), 23:11:13 INFO - 'default_actions': ('clobber', 23:11:13 INFO - 'read-buildbot-config', 23:11:13 INFO - 'download-and-extract', 23:11:13 INFO - 'populate-webroot', 23:11:13 INFO - 'create-virtualenv', 23:11:13 INFO - 'install', 23:11:13 INFO - 'run-tests'), 23:11:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:11:13 INFO - 'download_minidump_stackwalk': True, 23:11:13 INFO - 'download_symbols': 'ondemand', 23:11:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:11:13 INFO - 'tooltool.py': '/tools/tooltool.py', 23:11:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:11:13 INFO - '/tools/misc-python/virtualenv.py')}, 23:11:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:11:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:11:13 INFO - 'installer_path': 'installer.exe', 23:11:13 INFO - 'log_level': 'info', 23:11:13 INFO - 'log_name': 'talos', 23:11:13 INFO - 'log_to_console': True, 23:11:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:11:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:11:13 INFO - 'opt_config_files': (), 23:11:13 INFO - 'pip_index': False, 23:11:13 INFO - 'sps_profile': False, 23:11:13 INFO - 'sps_profile_interval': 0, 23:11:13 INFO - 'suite': 'dromaeojs', 23:11:13 INFO - 'system_bits': '32', 23:11:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:11:13 INFO - 'title': 'talos-linux64-ix-010', 23:11:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:11:13 INFO - 'use_talos_json': True, 23:11:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:11:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:11:13 INFO - 'work_dir': 'build'} 23:11:13 INFO - [mozharness: 2016-07-27 06:11:13.810882Z] Running clobber step. 23:11:13 INFO - Running pre-action listener: _resource_record_pre_action 23:11:13 INFO - Running main action method: clobber 23:11:13 INFO - rmtree: /builds/slave/test/build 23:11:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:11:14 INFO - Running post-action listener: _resource_record_post_action 23:11:14 INFO - [mozharness: 2016-07-27 06:11:14.282583Z] Finished clobber step (success) 23:11:14 INFO - [mozharness: 2016-07-27 06:11:14.282685Z] Running read-buildbot-config step. 23:11:14 INFO - Running pre-action listener: _resource_record_pre_action 23:11:14 INFO - Running main action method: read_buildbot_config 23:11:14 INFO - Using buildbot properties: 23:11:14 INFO - { 23:11:14 INFO - "project": "", 23:11:14 INFO - "product": "firefox", 23:11:14 INFO - "script_repo_revision": "production", 23:11:14 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:11:14 INFO - "repository": "", 23:11:14 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 23:11:14 INFO - "buildid": "20160726200813", 23:11:14 INFO - "pgo_build": "True", 23:11:14 INFO - "basedir": "/builds/slave/test", 23:11:14 INFO - "buildnumber": 68, 23:11:14 INFO - "slavename": "talos-linux64-ix-010", 23:11:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:11:14 INFO - "platform": "ubuntu64_hw", 23:11:14 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 23:11:14 INFO - "branch": "autoland", 23:11:14 INFO - "repo_path": "integration/autoland", 23:11:14 INFO - "revision": "46de97426f7b5467a37e1512ed022f2908d8626a", 23:11:14 INFO - "stage_platform": "linux64-pgo", 23:11:14 INFO - "builduid": "e8052f853a4543a59073aa6aaa3f543b", 23:11:14 INFO - "slavebuilddir": "test" 23:11:14 INFO - } 23:11:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:11:14 INFO - Running post-action listener: _resource_record_post_action 23:11:14 INFO - [mozharness: 2016-07-27 06:11:14.285138Z] Finished read-buildbot-config step (success) 23:11:14 INFO - [mozharness: 2016-07-27 06:11:14.285228Z] Running download-and-extract step. 23:11:14 INFO - Running pre-action listener: _resource_record_pre_action 23:11:14 INFO - Running main action method: download_and_extract 23:11:14 INFO - mkdir: /builds/slave/test/build/tests 23:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:14 INFO - https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:11:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:11:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:11:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:14 INFO - Downloaded 1361 bytes. 23:11:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:11:14 INFO - Using the following test package requirements: 23:11:14 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:11:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:11:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:11:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'jsshell-linux-x86_64.zip'], 23:11:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:11:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:11:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:11:14 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:11:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:11:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:11:14 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:11:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:14 INFO - https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:11:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:11:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:11:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:15 INFO - Downloaded 24311758 bytes. 23:11:15 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'] 23:11:15 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 23:11:17 INFO - Return code: 0 23:11:17 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 23:11: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')]} 23:11:17 INFO - https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:11:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:11:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:11:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:18 INFO - Downloaded 24311758 bytes. 23:11:18 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'] 23:11:18 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 23:11:19 INFO - Return code: 0 23:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:19 INFO - https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:11:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:11:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:11:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:21 INFO - Downloaded 14413725 bytes. 23:11:21 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'] 23:11:21 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 23:11:21 INFO - Return code: 0 23:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:21 INFO - https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BM0a7PdPT5WqE-0GrqReAg/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 23:11:22 INFO - Downloaded 60888188 bytes. 23:11:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:11:22 INFO - mkdir: /builds/slave/test/properties 23:11:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:11:22 INFO - Writing to file /builds/slave/test/properties/build_url 23:11:22 INFO - Contents: 23:11:22 INFO - build_url:https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:11:23 INFO - Running post-action listener: _resource_record_post_action 23:11:23 INFO - Running post-action listener: set_extra_try_arguments 23:11:23 INFO - [mozharness: 2016-07-27 06:11:23.657268Z] Finished download-and-extract step (success) 23:11:23 INFO - [mozharness: 2016-07-27 06:11:23.657540Z] Running populate-webroot step. 23:11:23 INFO - Running pre-action listener: _resource_record_pre_action 23:11:23 INFO - Running main action method: populate_webroot 23:11:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:11:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:11:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:11:23 INFO - '--tppagecycles', 23:11:23 INFO - '7'], 23:11:23 INFO - 'tests': ['tsvgm']}}, 23:11:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:11:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:11:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:11:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:11:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['damp', 'tps']}, 23:11:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'tests': ['damp', 'tps']}, 23:11:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['dromaeo_dom']}, 23:11:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:11:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['basic_compositor_video']}, 23:11:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:11:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'other-e10s': {'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['a11yr', 23:11:23 INFO - 'ts_paint', 23:11:23 INFO - 'tpaint', 23:11:23 INFO - 'sessionrestore', 23:11:23 INFO - 'sessionrestore_no_auto_restore', 23:11:23 INFO - 'tabpaint']}, 23:11:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['tsvgx', 23:11:23 INFO - 'tsvgr_opacity', 23:11:23 INFO - 'tart', 23:11:23 INFO - 'tscrollx', 23:11:23 INFO - 'cart']}, 23:11:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:11:23 INFO - 'tsvgr_opacity', 23:11:23 INFO - 'tart', 23:11:23 INFO - 'tscrollx', 23:11:23 INFO - 'cart']}, 23:11:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'talos_options': ['--disable-e10s'], 23:11:23 INFO - 'tests': ['tp5o']}, 23:11:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'tests': ['tp5o']}, 23:11:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'talos_options': ['--disable-e10s', 23:11:23 INFO - '--xperf_path', 23:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:23 INFO - 'tests': ['tp5n']}, 23:11:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:11:23 INFO - 'talos_options': ['--xperf_path', 23:11:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:23 INFO - 'tests': ['tp5n']}}, 23:11:23 INFO - 'talos.zip': {'path': '', 23:11:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:11:23 INFO - Running post-action listener: _resource_record_post_action 23:11:23 INFO - [mozharness: 2016-07-27 06:11:23.673324Z] Finished populate-webroot step (success) 23:11:23 INFO - [mozharness: 2016-07-27 06:11:23.673429Z] Running create-virtualenv step. 23:11:23 INFO - Running pre-action listener: _resource_record_pre_action 23:11:23 INFO - Running main action method: create_virtualenv 23:11:23 INFO - Creating virtualenv /builds/slave/test/build/venv 23:11:23 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 23:11:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:11:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:11:23 INFO - Using real prefix '/usr' 23:11:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:11:24 INFO - Installing distribute.............................................................................................................................................................................................done. 23:11:26 INFO - Installing pip.................done. 23:11:26 INFO - Return code: 0 23:11:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:11:26 INFO - Using env: {'DISPLAY': ':0', 23:11:26 INFO - 'HOME': '/home/cltbld', 23:11:26 INFO - 'LANG': 'en_US.UTF-8', 23:11:26 INFO - 'LANGUAGE': 'en_US:en', 23:11:26 INFO - 'LOGNAME': 'cltbld', 23:11:26 INFO - 'MAIL': '/var/mail/cltbld', 23:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:26 INFO - 'MOZ_NO_REMOTE': '1', 23:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:26 INFO - 'NO_EM_RESTART': '1', 23:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:26 INFO - 'PWD': '/builds/slave/test', 23:11:26 INFO - 'SHELL': '/bin/bash', 23:11:26 INFO - 'SHLVL': '1', 23:11:26 INFO - 'TERM': 'linux', 23:11:26 INFO - 'TMOUT': '86400', 23:11:26 INFO - 'USER': 'cltbld', 23:11:26 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:26 INFO - '_': '/tools/buildbot/bin/python'} 23:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:26 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 23:11:26 INFO - Cleaning up... 23:11:26 INFO - Return code: 0 23:11:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:11:26 INFO - Using env: {'DISPLAY': ':0', 23:11:26 INFO - 'HOME': '/home/cltbld', 23:11:26 INFO - 'LANG': 'en_US.UTF-8', 23:11:26 INFO - 'LANGUAGE': 'en_US:en', 23:11:26 INFO - 'LOGNAME': 'cltbld', 23:11:26 INFO - 'MAIL': '/var/mail/cltbld', 23:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:26 INFO - 'MOZ_NO_REMOTE': '1', 23:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:26 INFO - 'NO_EM_RESTART': '1', 23:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:26 INFO - 'PWD': '/builds/slave/test', 23:11:26 INFO - 'SHELL': '/bin/bash', 23:11:26 INFO - 'SHLVL': '1', 23:11:26 INFO - 'TERM': 'linux', 23:11:26 INFO - 'TMOUT': '86400', 23:11:26 INFO - 'USER': 'cltbld', 23:11:26 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:26 INFO - '_': '/tools/buildbot/bin/python'} 23:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:26 INFO - Downloading/unpacking psutil>=3.1.1 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:11:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:28 INFO - Installing collected packages: psutil 23:11:28 INFO - Running setup.py install for psutil 23:11:28 INFO - building 'psutil._psutil_linux' extension 23:11:28 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 23:11:28 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 23:11:28 INFO - building 'psutil._psutil_posix' extension 23:11:28 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 23:11:29 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 23:11:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:29 INFO - Successfully installed psutil 23:11:29 INFO - Cleaning up... 23:11:29 INFO - Return code: 0 23:11:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:11:29 INFO - Using env: {'DISPLAY': ':0', 23:11:29 INFO - 'HOME': '/home/cltbld', 23:11:29 INFO - 'LANG': 'en_US.UTF-8', 23:11:29 INFO - 'LANGUAGE': 'en_US:en', 23:11:29 INFO - 'LOGNAME': 'cltbld', 23:11:29 INFO - 'MAIL': '/var/mail/cltbld', 23:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:29 INFO - 'MOZ_NO_REMOTE': '1', 23:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:29 INFO - 'NO_EM_RESTART': '1', 23:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:29 INFO - 'PWD': '/builds/slave/test', 23:11:29 INFO - 'SHELL': '/bin/bash', 23:11:29 INFO - 'SHLVL': '1', 23:11:29 INFO - 'TERM': 'linux', 23:11:29 INFO - 'TMOUT': '86400', 23:11:29 INFO - 'USER': 'cltbld', 23:11:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:29 INFO - '_': '/tools/buildbot/bin/python'} 23:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:11:31 INFO - Installing collected packages: mozsystemmonitor 23:11:31 INFO - Running setup.py install for mozsystemmonitor 23:11:31 INFO - Successfully installed mozsystemmonitor 23:11:31 INFO - Cleaning up... 23:11:31 INFO - Return code: 0 23:11:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:31 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:31 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 23:11:31 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 23:11:31 INFO - Using env: {'DISPLAY': ':0', 23:11:31 INFO - 'HOME': '/home/cltbld', 23:11:31 INFO - 'LANG': 'en_US.UTF-8', 23:11:31 INFO - 'LANGUAGE': 'en_US:en', 23:11:31 INFO - 'LOGNAME': 'cltbld', 23:11:31 INFO - 'MAIL': '/var/mail/cltbld', 23:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:31 INFO - 'MOZ_NO_REMOTE': '1', 23:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:31 INFO - 'NO_EM_RESTART': '1', 23:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:31 INFO - 'PWD': '/builds/slave/test', 23:11:31 INFO - 'SHELL': '/bin/bash', 23:11:31 INFO - 'SHLVL': '1', 23:11:31 INFO - 'TERM': 'linux', 23:11:31 INFO - 'TMOUT': '86400', 23:11:31 INFO - 'USER': 'cltbld', 23:11:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:31 INFO - '_': '/tools/buildbot/bin/python'} 23:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:31 INFO - Downloading/unpacking jsonschema==2.5.1 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:11:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:11:33 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 23:11:33 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 23:11:33 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 23:11:33 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 23:11:33 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 23:11:33 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 23:11:33 INFO - Downloading functools32-3.2.3-2.tar.gz 23:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:11:33 INFO - warning: no files found matching '*.txt' 23:11:33 INFO - no previously-included directories found matching 'build' 23:11:33 INFO - no previously-included directories found matching 'dist' 23:11:33 INFO - no previously-included directories found matching '.git*' 23:11:33 INFO - Installing collected packages: jsonschema, functools32 23:11:33 INFO - Running setup.py install for functools32 23:11:34 INFO - warning: no files found matching '*.txt' 23:11:34 INFO - no previously-included directories found matching 'build' 23:11:34 INFO - no previously-included directories found matching 'dist' 23:11:34 INFO - no previously-included directories found matching '.git*' 23:11:34 INFO - Successfully installed jsonschema functools32 23:11:34 INFO - Cleaning up... 23:11:34 INFO - Return code: 0 23:11:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:11:34 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')]} 23:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:34 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')]} 23:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:34 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:34 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 23:11:34 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 23:11:34 INFO - Using env: {'DISPLAY': ':0', 23:11:34 INFO - 'HOME': '/home/cltbld', 23:11:34 INFO - 'LANG': 'en_US.UTF-8', 23:11:34 INFO - 'LANGUAGE': 'en_US:en', 23:11:34 INFO - 'LOGNAME': 'cltbld', 23:11:34 INFO - 'MAIL': '/var/mail/cltbld', 23:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:34 INFO - 'MOZ_NO_REMOTE': '1', 23:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:34 INFO - 'NO_EM_RESTART': '1', 23:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:34 INFO - 'PWD': '/builds/slave/test', 23:11:34 INFO - 'SHELL': '/bin/bash', 23:11:34 INFO - 'SHLVL': '1', 23:11:34 INFO - 'TERM': 'linux', 23:11:34 INFO - 'TMOUT': '86400', 23:11:34 INFO - 'USER': 'cltbld', 23:11:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:34 INFO - '_': '/tools/buildbot/bin/python'} 23:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:11:34 INFO - Cleaning up... 23:11:34 INFO - Return code: 0 23:11:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:11:34 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')]} 23:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:34 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')]} 23:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:34 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:34 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 23:11:34 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 23:11:34 INFO - Using env: {'DISPLAY': ':0', 23:11:34 INFO - 'HOME': '/home/cltbld', 23:11:34 INFO - 'LANG': 'en_US.UTF-8', 23:11:34 INFO - 'LANGUAGE': 'en_US:en', 23:11:34 INFO - 'LOGNAME': 'cltbld', 23:11:34 INFO - 'MAIL': '/var/mail/cltbld', 23:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:34 INFO - 'MOZ_NO_REMOTE': '1', 23:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:34 INFO - 'NO_EM_RESTART': '1', 23:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:34 INFO - 'PWD': '/builds/slave/test', 23:11:34 INFO - 'SHELL': '/bin/bash', 23:11:34 INFO - 'SHLVL': '1', 23:11:34 INFO - 'TERM': 'linux', 23:11:34 INFO - 'TMOUT': '86400', 23:11:34 INFO - 'USER': 'cltbld', 23:11:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:34 INFO - '_': '/tools/buildbot/bin/python'} 23:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:34 INFO - Downloading/unpacking blobuploader==1.2.4 23:11:34 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 23:11:34 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 23:11:34 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 23:11:34 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 23:11:34 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 23:11:34 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 23:11:36 INFO - Downloading blobuploader-1.2.4.tar.gz 23:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:11:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:11:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:36 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 23:11:37 INFO - Downloading docopt-0.6.1.tar.gz 23:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:11:37 INFO - Installing collected packages: blobuploader, requests, docopt 23:11:37 INFO - Running setup.py install for blobuploader 23:11:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:11:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:11:37 INFO - Running setup.py install for requests 23:11:37 INFO - Running setup.py install for docopt 23:11:37 INFO - Successfully installed blobuploader requests docopt 23:11:37 INFO - Cleaning up... 23:11:37 INFO - Return code: 0 23:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:37 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:37 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 23:11:37 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 23:11:37 INFO - Using env: {'DISPLAY': ':0', 23:11:37 INFO - 'HOME': '/home/cltbld', 23:11:37 INFO - 'LANG': 'en_US.UTF-8', 23:11:37 INFO - 'LANGUAGE': 'en_US:en', 23:11:37 INFO - 'LOGNAME': 'cltbld', 23:11:37 INFO - 'MAIL': '/var/mail/cltbld', 23:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:37 INFO - 'MOZ_NO_REMOTE': '1', 23:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:37 INFO - 'NO_EM_RESTART': '1', 23:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:37 INFO - 'PWD': '/builds/slave/test', 23:11:37 INFO - 'SHELL': '/bin/bash', 23:11:37 INFO - 'SHLVL': '1', 23:11:37 INFO - 'TERM': 'linux', 23:11:37 INFO - 'TMOUT': '86400', 23:11:37 INFO - 'USER': 'cltbld', 23:11:37 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:37 INFO - '_': '/tools/buildbot/bin/python'} 23:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:38 INFO - Running setup.py (path:/tmp/pip-l08YE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:38 INFO - Running setup.py (path:/tmp/pip-ceSSkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:38 INFO - Running setup.py (path:/tmp/pip-91OftL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:38 INFO - Running setup.py (path:/tmp/pip-f64WBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:38 INFO - Running setup.py (path:/tmp/pip-grjvpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:38 INFO - Running setup.py (path:/tmp/pip-qd6NQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:38 INFO - Running setup.py (path:/tmp/pip-LSjK4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:38 INFO - Running setup.py (path:/tmp/pip-yGZewY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:38 INFO - Running setup.py (path:/tmp/pip-MQZvu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:38 INFO - Running setup.py (path:/tmp/pip-kG5egJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:38 INFO - Running setup.py (path:/tmp/pip-jhoEXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:38 INFO - Running setup.py (path:/tmp/pip-GCSoSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:38 INFO - Running setup.py (path:/tmp/pip-1Dc0Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:38 INFO - Running setup.py (path:/tmp/pip-rik_NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:39 INFO - Running setup.py (path:/tmp/pip-ONg8gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:39 INFO - Running setup.py (path:/tmp/pip-SVltpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:39 INFO - Running setup.py (path:/tmp/pip-6EGweQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:11:39 INFO - Running setup.py install for manifestparser 23:11:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:11:39 INFO - Running setup.py install for mozcrash 23:11:39 INFO - Running setup.py install for mozdebug 23:11:39 INFO - Running setup.py install for mozdevice 23:11:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:11:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:11:39 INFO - Running setup.py install for mozfile 23:11:39 INFO - Running setup.py install for mozhttpd 23:11:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:11:39 INFO - Running setup.py install for mozinfo 23:11:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:11:39 INFO - Running setup.py install for mozInstall 23:11:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Running setup.py install for mozleak 23:11:40 INFO - Running setup.py install for mozlog 23:11:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Running setup.py install for moznetwork 23:11:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Running setup.py install for mozprocess 23:11:40 INFO - Running setup.py install for mozprofile 23:11:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Running setup.py install for mozrunner 23:11:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:11:40 INFO - Running setup.py install for mozscreenshot 23:11:40 INFO - Running setup.py install for moztest 23:11:40 INFO - Running setup.py install for mozversion 23:11:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:11:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:11:41 INFO - Cleaning up... 23:11:41 INFO - Return code: 0 23:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:41 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')]} 23:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:41 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')]} 23:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:41 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:41 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 23:11:41 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 23:11:41 INFO - Using env: {'DISPLAY': ':0', 23:11:41 INFO - 'HOME': '/home/cltbld', 23:11:41 INFO - 'LANG': 'en_US.UTF-8', 23:11:41 INFO - 'LANGUAGE': 'en_US:en', 23:11:41 INFO - 'LOGNAME': 'cltbld', 23:11:41 INFO - 'MAIL': '/var/mail/cltbld', 23:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:41 INFO - 'MOZ_NO_REMOTE': '1', 23:11:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:41 INFO - 'NO_EM_RESTART': '1', 23:11:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:41 INFO - 'PWD': '/builds/slave/test', 23:11:41 INFO - 'SHELL': '/bin/bash', 23:11:41 INFO - 'SHLVL': '1', 23:11:41 INFO - 'TERM': 'linux', 23:11:41 INFO - 'TMOUT': '86400', 23:11:41 INFO - 'USER': 'cltbld', 23:11:41 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:41 INFO - '_': '/tools/buildbot/bin/python'} 23:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:41 INFO - Running setup.py (path:/tmp/pip-1KBNnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:41 INFO - Running setup.py (path:/tmp/pip-L7bCPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:41 INFO - Running setup.py (path:/tmp/pip-6uh7aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:41 INFO - Running setup.py (path:/tmp/pip-kUZ31v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:41 INFO - Running setup.py (path:/tmp/pip-posL3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:41 INFO - Running setup.py (path:/tmp/pip-jMFets-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:41 INFO - Running setup.py (path:/tmp/pip-nz5CaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:41 INFO - Running setup.py (path:/tmp/pip-srbCGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:41 INFO - Running setup.py (path:/tmp/pip-JWxIpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11:41 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)) 23:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:41 INFO - Running setup.py (path:/tmp/pip-d1xqLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:42 INFO - Running setup.py (path:/tmp/pip-UUjMvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:42 INFO - Running setup.py (path:/tmp/pip-H4bB2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:42 INFO - Running setup.py (path:/tmp/pip-r7LJJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:42 INFO - Running setup.py (path:/tmp/pip-g5V2vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:42 INFO - Running setup.py (path:/tmp/pip-FS5PlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:42 INFO - Running setup.py (path:/tmp/pip-LEQ1c3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11:42 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)) 23:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:42 INFO - Running setup.py (path:/tmp/pip-xosOIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11:42 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)) 23:11:42 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)) 23:11:42 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)) 23:11:42 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)) 23:11:42 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)) 23:11:42 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)) 23:11:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11: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 23:11:44 INFO - Downloading blessings-1.6.tar.gz 23:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:11:44 INFO - Installing collected packages: blessings 23:11:44 INFO - Running setup.py install for blessings 23:11:44 INFO - Successfully installed blessings 23:11:44 INFO - Cleaning up... 23:11:44 INFO - Return code: 0 23:11:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:44 INFO - Reading from file tmpfile_stdout 23:11:44 INFO - Current package versions: 23:11:44 INFO - argparse == 1.2.1 23:11:44 INFO - blessings == 1.6 23:11:44 INFO - blobuploader == 1.2.4 23:11:44 INFO - docopt == 0.6.1 23:11:44 INFO - functools32 == 3.2.3-2 23:11:44 INFO - jsonschema == 2.5.1 23:11:44 INFO - manifestparser == 1.1 23:11:44 INFO - mozInstall == 1.12 23:11:44 INFO - mozcrash == 0.17 23:11:44 INFO - mozdebug == 0.1 23:11:44 INFO - mozdevice == 0.48 23:11:44 INFO - mozfile == 1.2 23:11:44 INFO - mozhttpd == 0.7 23:11:44 INFO - mozinfo == 0.9 23:11:44 INFO - mozleak == 0.1 23:11:44 INFO - mozlog == 3.3 23:11:44 INFO - moznetwork == 0.27 23:11:44 INFO - mozprocess == 0.23 23:11:44 INFO - mozprofile == 0.28 23:11:44 INFO - mozrunner == 6.12 23:11:44 INFO - mozscreenshot == 0.1 23:11:44 INFO - mozsystemmonitor == 0.3 23:11:44 INFO - moztest == 0.7 23:11:44 INFO - mozversion == 1.4 23:11:44 INFO - psutil == 3.1.1 23:11:44 INFO - requests == 1.2.3 23:11:44 INFO - wsgiref == 0.1.2 23:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:44 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')]} 23:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:44 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')]} 23:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:44 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 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11:44 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 23:11:44 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 23:11:44 INFO - Using env: {'DISPLAY': ':0', 23:11:44 INFO - 'HOME': '/home/cltbld', 23:11:44 INFO - 'LANG': 'en_US.UTF-8', 23:11:44 INFO - 'LANGUAGE': 'en_US:en', 23:11:44 INFO - 'LOGNAME': 'cltbld', 23:11:44 INFO - 'MAIL': '/var/mail/cltbld', 23:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:44 INFO - 'MOZ_NO_REMOTE': '1', 23:11:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:44 INFO - 'NO_EM_RESTART': '1', 23:11:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:44 INFO - 'PWD': '/builds/slave/test', 23:11:44 INFO - 'SHELL': '/bin/bash', 23:11:44 INFO - 'SHLVL': '1', 23:11:44 INFO - 'TERM': 'linux', 23:11:44 INFO - 'TMOUT': '86400', 23:11:44 INFO - 'USER': 'cltbld', 23:11:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:44 INFO - '_': '/tools/buildbot/bin/python'} 23:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 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)) 23:11:45 INFO - Cleaning up... 23:11:45 INFO - Return code: 0 23:11:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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')]} 23:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a36180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', '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 23:11: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', 'jsonschema'] in /builds/slave/test/build 23:11: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 jsonschema 23:11:45 INFO - Using env: {'DISPLAY': ':0', 23:11:45 INFO - 'HOME': '/home/cltbld', 23:11:45 INFO - 'LANG': 'en_US.UTF-8', 23:11:45 INFO - 'LANGUAGE': 'en_US:en', 23:11:45 INFO - 'LOGNAME': 'cltbld', 23:11:45 INFO - 'MAIL': '/var/mail/cltbld', 23:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:45 INFO - 'MOZ_NO_REMOTE': '1', 23:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:45 INFO - 'NO_EM_RESTART': '1', 23:11:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:45 INFO - 'PWD': '/builds/slave/test', 23:11:45 INFO - 'SHELL': '/bin/bash', 23:11:45 INFO - 'SHLVL': '1', 23:11:45 INFO - 'TERM': 'linux', 23:11:45 INFO - 'TMOUT': '86400', 23:11:45 INFO - 'USER': 'cltbld', 23:11:45 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:45 INFO - '_': '/tools/buildbot/bin/python'} 23:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:11:45 INFO - Cleaning up... 23:11:45 INFO - Return code: 0 23:11:45 INFO - Running post-action listener: _resource_record_post_action 23:11:45 INFO - Running post-action listener: _start_resource_monitoring 23:11:45 INFO - Starting resource monitoring. 23:11:45 INFO - [mozharness: 2016-07-27 06:11:45.362102Z] Finished create-virtualenv step (success) 23:11:45 INFO - [mozharness: 2016-07-27 06:11:45.362536Z] Running install step. 23:11:45 INFO - Running pre-action listener: _resource_record_pre_action 23:11:45 INFO - Running main action method: install 23:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:45 INFO - Reading from file tmpfile_stdout 23:11:45 INFO - Detecting whether we're running mozinstall >=1.0... 23:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:11:45 INFO - Reading from file tmpfile_stdout 23:11:45 INFO - Output received: 23:11:45 INFO - Usage: mozinstall [options] installer 23:11:45 INFO - Options: 23:11:45 INFO - -h, --help show this help message and exit 23:11:45 INFO - -d DEST, --destination=DEST 23:11:45 INFO - Directory to install application into. [default: 23:11:45 INFO - "/builds/slave/test"] 23:11:45 INFO - --app=APP Application being installed. [default: firefox] 23:11:45 INFO - mkdir: /builds/slave/test/build/application 23:11:45 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'] 23:11:45 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 23:12:02 INFO - Reading from file tmpfile_stdout 23:12:02 INFO - Output received: 23:12:02 INFO - /builds/slave/test/build/application/firefox/firefox 23:12:02 INFO - Running post-action listener: _resource_record_post_action 23:12:02 INFO - [mozharness: 2016-07-27 06:12:02.889029Z] Finished install step (success) 23:12:02 INFO - [mozharness: 2016-07-27 06:12:02.889347Z] Running run-tests step. 23:12:02 INFO - Running pre-action listener: _resource_record_pre_action 23:12:02 INFO - Running main action method: run_tests 23:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:12:02 INFO - Python 2.7.3 23:12:02 INFO - Return code: 0 23:12:02 INFO - grabbing minidump binary from tooltool 23:12:02 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')]} 23:12:02 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 0x1a343b0>, '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 0x1a352b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:12:02 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 23:12:02 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 23:12:02 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 23:12:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:12:02 INFO - Return code: 0 23:12:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:12:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:12:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 23:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 23:12:02 INFO - Using env: {'DISPLAY': ':0', 23:12:02 INFO - 'HOME': '/home/cltbld', 23:12:02 INFO - 'LANG': 'en_US.UTF-8', 23:12:02 INFO - 'LANGUAGE': 'en_US:en', 23:12:02 INFO - 'LOGNAME': 'cltbld', 23:12:02 INFO - 'MAIL': '/var/mail/cltbld', 23:12:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:12:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:02 INFO - 'MOZ_NO_REMOTE': '1', 23:12:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:02 INFO - 'NO_EM_RESTART': '1', 23:12:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:02 INFO - 'PWD': '/builds/slave/test', 23:12:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:12:02 INFO - 'SHELL': '/bin/bash', 23:12:02 INFO - 'SHLVL': '1', 23:12:02 INFO - 'TERM': 'linux', 23:12:02 INFO - 'TMOUT': '86400', 23:12:02 INFO - 'USER': 'cltbld', 23:12:02 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495', 23:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:02 INFO - '_': '/tools/buildbot/bin/python'} 23:12:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 23:12:03 INFO - mozversion application_buildid: 20160726200813 23:12:03 INFO - mozversion application_changeset: 46de97426f7b5467a37e1512ed022f2908d8626a 23:12:03 INFO - mozversion application_display_name: Nightly 23:12:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:12:03 INFO - mozversion application_name: Firefox 23:12:03 INFO - mozversion application_remotingname: firefox 23:12:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:12:03 INFO - mozversion application_vendor: Mozilla 23:12:03 INFO - mozversion application_version: 50.0a1 23:12:03 INFO - mozversion platform_buildid: 20160726200813 23:12:03 INFO - mozversion platform_changeset: 46de97426f7b5467a37e1512ed022f2908d8626a 23:12:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:12:03 INFO - mozversion platform_version: 50.0a1 23:12:03 INFO - using testdate: 1469599923 23:12:03 INFO - actual date: 1469599923 23:12:03 INFO - starting webserver on 'localhost:51930' 23:12:03 INFO - SUITE-START | Running 2 tests 23:12:03 INFO - TEST-START | dromaeo_css 23:12:03 INFO - operating with platform_type : linux_ 23:12:03 INFO - Initialising browser for dromaeo_css test... 23:12:03 INFO - TEST-INFO | started process 12744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprsSV5u/profile http://localhost:51930/getInfo.html) 23:12:06 INFO - PROCESS | 12744 | JavaScript error: , line 0: uncaught exception: undefined 23:12:07 INFO - PROCESS | 12744 | __metrics Screen width/height:1600/1200 23:12:07 INFO - PROCESS | 12744 | colorDepth:24 23:12:07 INFO - PROCESS | 12744 | Browser inner width/height: 1024/697 23:12:07 INFO - PROCESS | 12744 | __metrics 23:12:08 INFO - PROCESS | 12744 | 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" 23:12:08 INFO - PROCESS | 12744 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:12:09 INFO - TEST-INFO | 12744: exit 0 23:12:09 INFO - Browser initialized. 23:12:09 INFO - Running cycle 1/1 for dromaeo_css test... 23:12:09 INFO - TEST-INFO | started process 12824 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprsSV5u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:21 INFO - PROCESS | 12824 | RSS: Main: 158167040 23:12:21 INFO - PROCESS | 12824 | 23:13:12 INFO - PROCESS | 12824 | 1469599992814 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] 23:15:21 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51930/tests/dromaeo/cssquery-ext.html) 23:15:22 INFO - PROCESS | 12824 | RSS: Main: 251813888 23:15:22 INFO - PROCESS | 12824 | 23:18:24 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-ext.html (next: http://localhost:51930/tests/dromaeo/cssquery-jquery.html) 23:18:24 INFO - PROCESS | 12824 | RSS: Main: 249524224 23:18:24 INFO - PROCESS | 12824 | 23:21:27 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51930/tests/dromaeo/cssquery-mootools.html) 23:21:27 INFO - PROCESS | 12824 | RSS: Main: 221253632 23:21:27 INFO - PROCESS | 12824 | 23:24:29 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51930/tests/dromaeo/cssquery-prototype.html) 23:24:30 INFO - PROCESS | 12824 | RSS: Main: 219521024 23:24:30 INFO - PROCESS | 12824 | 23:27:32 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51930/tests/dromaeo/cssquery-yui.html) 23:27:33 INFO - PROCESS | 12824 | RSS: Main: 187453440 23:27:33 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | Cycle 1(1): loaded http://localhost:51930/tests/dromaeo/cssquery-yui.html (next: http://localhost:51930/tests/dromaeo/cssquery-dojo.html) 23:30:36 INFO - PROCESS | 12824 | RSS: Main: 268754944 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | __start_tp_report 23:30:36 INFO - PROCESS | 12824 | _x_x_mozilla_page_load 23:30:36 INFO - PROCESS | 12824 | _x_x_mozilla_page_load_details 23:30:36 INFO - PROCESS | 12824 | |i|pagename|runs| 23:30:36 INFO - PROCESS | 12824 | |0;dojo.html;3075.5694202811605;3081.784275100741;3106.64273608535;3112.0041586692255;3118.1269244611503;5291.470852914711;5298.682079075248;5355.598330125246;5399.109146990747;5409.188621706741;5342.91985620216;5375.973120134404;5382.757775900084;5421.457854214576;5441.183822426638;4812.326274321597;4887.218045112782;4916.115099282128;4929.4824043475455;5107.846764597068;4988.102141614508;5004.12427825131;5040.143175660129;5051.595872330205;5056.469070747574;4816.83141090062;4832.057748738997;4853.927191092136;4860.1008813369635;4968.577670897978;4887;4897.583705385046;4901.509849015106;4902.730349830758;4970.168044227936;4918.090153321611;4952.752362381866;4959.752012399365;4959.776810043557;4978.676386034932;5262.1317482615705;5391.353037635469;5440.619156659035;5445.809396671137;5508.3114610673665;5845.649261044308;5859.091840764689;5911.379305172981;5916.763329466816;5921.259842519685;5865.736041878125;5913.497352725023;5955.285365756094;5961.701914904281;5964.7912323068485;5269.420363759983;5373.247745315659;5427.43011983744;5429.212764149176;5499.50504454601;5944.82165535026;6001.36985616504;6009.06859436788;6010.4891084258325;6011.248593925759;5727.8854422912145;5767.913481297784;5779.595428319977;5780.219670344533;5783.566232532577;5623.718814059281;5727.226824378656;5779.797707080232;5781.8554536136935;5884.941150588525;5794.971025144847;5800.390958949334;5802.738876750556;5808.332041815161;5817.592768506164;5749.425057494301;5751.223584815996;5818.040023396177;5818.359980402237;5819.621724587888;4296.914061718748;4314.69797114199;4348.326009468537;4393.1872603568445;4394.868153955386;3827.2345530893376;3926.3521518949624;3976.622220889011;3994.900127496836;3999.960000399959;5475.561955043587;5480.451954804487;5482;5521.116621340566;5533.861653458615;5798.739056742456;5804.79683211094;5805.593608447368;5807.970960145172;5845.386234445407;5241.109011467984;5250.422453530108;5381.946180538144;5436.864078398072;5437.157240627709;4984.90030199394;4987.975060124749;4988.201887697951;4998.575121114746;5040.949590504121;5971.104334349795;5981.0729336952845;5983.311919129356;5984.401559844068;5986.790462333797;5540.750666219949;5743.655380677173;5783.277090363705;5794.681292528951;5800.24596802413;6011.128386384037;6015.248093988252;6016.338202797776;6047.879042419215;6075.088736689355;5677.205191273308;5723.656580605177;5724.112762521816;5728.398518155534;5729.742161602655;5640.32316122068;5711;5722.7710891563875;5818.970905145447;5832.154337621106;5641.774329026957;5650.943490565041;5655.321361436654;5680.715964201856;5702.315722113373;5628;5643.407442218508;5653.434656534314;5655.462731040545;5659.320881494247;5463.31708536433;5475.890482190413;5485.424030476902;5591.6365436246515;5595.04884169684;5625.774969001194;5655.7172141393585;5658.868226354663;5668.773249069991;5671.801486947937;5462.945370546403;5466.972665136808;5504.504594586507;5514.5864060194845;5532.889342213052 23:30:36 INFO - PROCESS | 12824 | |1;ext.html;586.6861229242356;622.1197006236176;624.4317670919464;634.1407392982509;640.3052687833706;36133;36773.08067298315;45704.771476142654;46089.769551152196;46547.767261163644;46840.53159468404;47243.76378118105;47298.29052564214;47342;47365.289520657134;9095.863562046574;9253.074692530727;9253.259739220846;9258.861117083248;9263.953680231589;9079.409838360518;9164.8625270621;9166.908330916705;9170.587323570455;9170.633174673005;9198.816023679556;9199.632014719436;9253.58358873849;9254.074592540726;9261.768455788591;9109.544522773867;9159.542022898895;9172;9177.954110229473;9180.403273787217;9003.09969003101;9133.40632858862;9177.908220917772;9180.03609620987;9185.448873067637;44563.77718111421;44893.551064489264;44939.55060449387;45008.77495612534;45115.774421128015;3528.8721369668956;3674.761140525857;3714.182879766447;3750.474933509311;3795.8291876865333;33881.152971175674;34521.309573808634;34533.13667158316;34681;34744.30511389783;4375.803088861009;4442;4442.689011769177;4444.066745983348;4582.518835522255;356971;360523;362392;363545;363790;60806.69596651988;61285;61319.693401532706;61323.69338153281;61415;63005.68497157485;63361.68319158375;63647;63652.68173659102;63724.681376592824;17168.313267469162;17301.4809555711;17419.912900435418;17426.91286543559;17436.651266974844;13240.668983275302;13328.666783330495;13336.399862006234;13347.199168049949;13349.46602135923;16256.43102491407;17616.64766704659;17676.823231767517;17703.38038168658;17713;6284.842878928063;6409.647469389229;6435.710393032325;6443.774467893695;6447.323031081763;963.4797209506788;1083.8049151152868;1101.7080473674482;1168.4449886303937;1178.9469473873696;32349.35301293961;32422.189445264055;32874.342513149604;32877.17807054843;33582.32835343328;1094.2285688589525;1106.319613437746;1145.2613064573304;1154.7690461907653;1181.8091153884545;17012.064336461364;17026.659466810594;17197.312107515558;17335.56661083458;17357.913210433868;141722;141823.29088354492;142167.2891635556;143178;143366;62065;62397.37602623916;62434.68782656058;62439;62460.687696561225;1250.6373151786063;1277.4826195390697;1277.8849903508728;1335.152178366744;1365.6449323175848;25762.87118564395;33899.83050084734;34181;34345.313093738485;34422;52156.956860861814;52594.21108683449;52745.20882186694;52787.73606131945;52804;416861;419601;419957;420408;420738;34178.82910585431;35743.28513429769;42804;42846.35730463983;43779.56220437755;48500.39873790795;49779.73348500008;49838.75080624719;50700;51085.23372149495;42819.785901070296;43634.781826090664;43751.124977500906;44533;45750.54249457596;42121.157576847676;43222;43562.55950491877;43622.34566481442;43625.78187109044;36117;38027;38117.428238577;40460;41705.58294417139;37223;38482.230355393294;38976;39602.40596390999;39625.603743963344;28085.71914280831;28135.81712265204;28469.145925622266;28513.28716782014;29916 23:30:36 INFO - PROCESS | 12824 | |2;jquery.html;360.23629904602245;389.6162280154048;400.5113761211322;401.7248184993279;419.4107279272621;14376.209308488029;17350.65298694027;19768.110435030423;20238.797612023893;20242.49393765153;19545.804541954578;19573.608527829434;19692.40922772319;19776.70334944977;19839.305624303153;5690.829275121732;5826.300843898727;5826.475617194452;5949.613275137124;5984.431479009487;5804.912926306109;5881.970590147043;5892.351841297454;5892.616979896315;5914.822555323347;5642.7742890284335;5761.913571296413;5804.245448091742;5869.9706501467435;5873.236479257691;5723.713814309289;5749.252597162342;5757.165211044396;5761.279840019997;5768.134779783045;4963.2058870580895;5092.388913330424;5143.588512918957;5199.558037566811;5207.401148867891;23297.76702232973;23405.765942340528;25662.358441038938;26208.73791262082;26312.079077232396;2457.4347899983104;2572.369769406507;2601.9869900650565;2607.4133320002848;3296.428785547277;16284.10437425941;16386.918065409598;16423.91788041052;16453.506394808057;17291.221895014754;2040.2247146084542;2057.5781964697203;2063.484128967758;2141.9464513387134;2235.5305385868824;67247.9999999995;74643;76270.20470803743;78931;99702.50148749282;14683.60249440359;17292.567685807957;18248.543786405182;18423.53941151455;18555.72166417505;32114.51828222532;32139.678603214135;32150;32159.196020099684;32204.67795322017;10080.647177348757;10120.937301583375;10287.897121028844;10367.32612380193;10454.68635940923;8077.959610202029;8085.676572937016;8323.1260717625;8421.536815475085;8442.113578074337;9006.279497640173;9087.863682044775;9088.954555227314;9133.178013978773;9331.860022099674;3394.4229480988292;3410.6759857813468;3474.2455060194943;3496.9125771855906;3513.332466831294;928.2063835420753;928.4429342394644;944.9385789923633;956.0200794185876;1027.6197806811379;19039.714404283946;20090.296839610837;20245.595088098155;20248.59502809965;20251;1019.7858449725493;1034.4155552113032;1053.2311412668794;1057.640402263219;1089.128697042363;10411.375317480824;11182.832257516144;11229.27009744364;11365;11367.431628418712;385117;389111;390924;519783;522471;67454.32545674579;102313;104845;105687;105971;909.4815954905772;1012.6354512375539;1027.091024443358;1029.2075102171436;1030.0832259289239;18138.81861181424;18187.45437636873;18648.067596620385;18675;18748.7187692182;44095;45368.092638146394;45884.35947524462;46220.306695398926;47772.2834157481;63674.68162659157;64001;64657.67671161802;65151.34848651642;66371;8285.337173026264;8392.412531123007;8412.032616248965;8471.830563388821;8501.574921254036;12766.42551085222;12881.484740610646;13204.27376494302;13838.37727302294;14396.712065758416;11474.368909709712;11906.166568340133;11935.462904169506;12089.939550302544;12328.19866708661;11933.105017123577;11987.64037078889;12079.818802717791;12091.334976576372;12228;11233.32600044;11299.717507062389;11821.468033938665;11932.343721095423;12111.394430278628;8193.918060819315;8515.74452766418;8571.871421928801;8588.141185881612;8672.609732561923;8578.399512034348;11044.723881903017;11348.092152627605;11442.199046066695;11511.827322590336 23:30:36 INFO - PROCESS | 12824 | |3;mootools.html;1407.9440419685236;1493.8431464696207;1582.2168026826723;1691.8561922236609;1766.3729376071494;8743.825123497534;9441.905580944189;9462.574184161713;9468;9471.668491602795;7838.255365740246;8591.742247732578;8754.08082151373;8805.295576353892;9273.953630231856;2397.220903206456;2429.586970215059;2431.6109422492405;2432.7567243275707;2433.3429973907123;2416.2871952773976;2439.499902519988;2466.851988880664;2469.814763892706;2471.9011239550396;2438;2443.1571107967725;2448.0085565345976;2465.864377459239;2468.1854987854053;2372.027468737818;2375.857448553092;2384.654225137354;2392.413858604653;2433.549793288247;2331.079223706626;2336.3458231695154;2337.2988103569032;2340.625499920022;2341.695579574661;9152.725418237396;9286;9404.764880878032;9415.811683766286;9416.90583094167;5503.229547863302;5647.8588035299035;5663;5663.0655941769555;5669.2346533218315;3368.292658541709;3538.575370955476;3641.9271614567824;3685.9631403686158;3710.4619830124752;1082.7617924056694;1084.0568705226424;1093.4423444043516;1096.660035389032;1115.7043383503378;13020.544280950216;13061.542846000344;13124.343782810822;13136.34318284082;13194.736105277841;4486.461624605068;4662.393888794503;4781.832635857798;4815.614750819926;4818.445878723922;10115.747106322253;10186.592536298613;10196.847047294297;10203.438810865326;10204.591816327264;5541.307336582927;5547.389787123413;5548.889022219533;5551.028570000234;5551.8889622207325;4489.057297967398;4881.877953051202;4950.059488697136;5009.198528235538;5189.636725429184;3875.6124387561017;3911.9022024449046;3979.422983667352;4031.8992025199605;4036.8788936331957;1142.3774043146504;1147.0880649883363;1147.6442302886132;1150.5972909481613;1152.458344578047;848.7835601921471;969.9806003880024;977.1010670183448;981.1954197558076;1024.8770147582338;6071.787487437919;6089.451949324493;6138.202033735586;6285.5914365567005;6377.1390862233;423.1452466018687;426.4988638349988;428.3467711739622;437.4226021651453;447.2262985035906;4062.289099407592;4301;4310.137972405531;4324.697271190987;4371.562843715603;10988.725281868017;11241.887581124247;11305.71735706614;11319.660410187707;11322.60370887015;10045.29682922203;10312.587496500057;10330.793384132276;11257.943710281395;11265;8497.447665901696;8677.1756683115;8683.478991260545;8687.52218627969;8691.087435819274;5935.228420305505;6022.126791615104;6048;6087.086936959491;6201.379862013762;10473.31923424975;10774.138068954622;10781.62264320745;10855.620053298097;10872.728181795519;10568.313059651098;10727.946360268148;11131.332120072822;11140.665780026613;11142.442877856236;7342.706291748484;7844.6077696113825;7994.840103197787;8104.797380065545;8228.136045715113;8679.34904882149;8838.513881736566;8859.734207973772;8863.246624037029;8868.33487488428;7736.342410894912;7738.2261773822165;7933.286004259414;7955.20447955223;8014.078380986261;7465.477416580788;7581.50720203185;7582.734604288823;7730.574818384818;7860.764177074697;6912.688928998106;7056.6471676417;7057.258987806206;7127.572345659277;7135.42916566663;5199.66202196856;5282.445343238905;5322.813701520428;5351.384590772111;5516.806911758069;5290.629655924048;5309.601779866603;5310.654807437504;5353.571714262772;5442.210879422382 23:30:36 INFO - PROCESS | 12824 | |4;prototype.html;315.16324159356884;320.6761171217071;324.58777352761985;327.0809026635154;330.97352211823056;5345.423100185445;5397.649152805065;5409.810656627019;5420.268263784388;5431.239626452295;5061.822836200734;5150.871228219296;5193.584513238941;5195.532402083812;5363.275957745703;4042.3128068228325;4080.693947953908;4144.668426525871;4146.631611568176;4178.289690752564;4152.8131234094535;4173.916521669565;4189.549113768434;4190.832366705328;4195.496540415154;4065.4714887064642;4122.567130451304;4128.1330920506725;4154.106867023589;4159.168166366723;4118.464599602063;4135.441715368447;4143.834246630131;4148.8340466381305;4159.334506478979;3680.7975561344115;3684.171061511165;3690.2435000824767;3692.5568931728367;3710.499082623838;5095.490450954912;5226.7909283628605;5232.319798426218;5240.633155679104;5242;4655.4180727409075;4671.719696818202;4673.649476289258;4712.764361781931;4759.214729569617;5449.719315960746;5463.344398672185;5484.751251986706;5500.889982200333;5513.917291240635;3918.196769662241;3974;3984.860529881469;4009.7794621295816;4013.7591744495426;5689.288838895138;5693.491224825425;5706.543476521868;5715.628484148517;5738.167965644958;4113.1568028554075;4114.333694853589;4151.750894946253;4156.43888075112;4160;4033.1328764315226;4202.243596152722;4204.684648651363;4209.5579964103945;4214.452121224283;4678.204705200124;4682.321063445781;4693.647976401783;4749.145153872267;4749.976250118796;5577.358603760538;5581.888362232733;5599.412061733541;5613.382527921925;5635.408282130399;1330.7804212304861;1431.3272761801936;1456.6649670575614;1456.6940942402002;1457.4898785425228;918.2745630951473;950.6482601437513;960.3085778239656;971.1987610221596;983.9655612053543;5306.46935306474;5311.7344132793205;5315.6279060465395;5332.893507064688;5355.678659280456;5511.779528818883;5704.401037891044;5721.885562288731;5733.219388596527;5745.88508229833;5237.607179461479;5258.2638430619745;5282.1548552232225;5304.177852432879;5315.681059136464;2979.2402937250863;3012.2469382654335;3014.5779590857296;3020.0486846643676;3033.0597514770493;5646.887062258732;5792.681402522818;5809.767609295665;5893.381194974551;5915.58590898633;5721.971390143022;5743.827685169368;5749.13762935563;5749.568782341257;5786.566007549451;5823.621464604787;5852.268466441695;5860.970695146497;5865.736041878125;5870.119482077637;5439.374471935778;5724.685142317213;5753.683547404933;5800.346901133157;5814.418558144152;5689.402612725769;5700.543956483389;5701.942980570141;5732.598718089694;5766.538676905921;5745.770169193185;5893.381194974638;5955.880882382415;5981.252343457068;6009.308929473166;4052.63526282636;4065.329220678555;4066.512018557674;4079.73481723687;4094.1197642507004;5827.24245848037;5843.328017278068;5868.559858010581;5875.764969401176;5876.647401155944;5437;5531.474509921551;5599.300087489064;5636.689982051026;5640.54875609942;5625.268715067015;5629;5631.605787594993;5631.915521267102;5717.199592057199;5344.919826202532;5431.4840090191365;5493;5641.830745077655;5656.943430565641;5490.6980116094;5546;5625.268715067015;5634.802781902614;5636.830895073154;5459.617826752089;5493.2858728366655;5495.477929596682;5507.889842203053;5542.501174894279 23:30:36 INFO - PROCESS | 12824 | |5;yui.html;770.4491288728558;775.6121939030484;790.8774140008297;815.8042069903225;867.5748883047306;14452.855471445282;15053.473128440506;15169.01401408909;15202.54392368228;15232.162231077285;14521.419143234258;14658.340374683139;15233.086014839117;15334.156621385819;15349.923250383761;809.8339840332733;821.36344333142;823.8640624296984;824.2375802382802;824.2664029014182;688.6178171115029;793.1671744668105;797.5852556670528;798.1539568057848;825.6160885188386;865.965361385547;866.8613021916494;870.1429092344036;870.1863757386832;870.2646263906988;859.7549698335944;864.887564616599;866.618687777376;866.7833041739565;868.7089824908663;511.5856156513199;599.865030368168;612.2775125352083;615.5660259517075;629.8614304852971;14800.777988330183;14824;14830.184339861304;14837.851621483755;14868.479603213944;14339.641508962255;14439.422423103064;14940.850591494054;14964.08619313645;15214.46749363778;12594.37028148589;12793.232406055666;12857.4214160363;12981.156224845356;13712.93143534276;4256.063665993507;4272.273713468718;4294.742315461082;4306.44016277885;4335.067960388532;76619.6169019157;77609.22390776077;78870.21129788685;79201.60399198024;79921.60039199825;11657.825132623017;11686.18196726238;11771.470283837249;11853.644390668122;12035.939820300842;20139.798602014085;20615.79384206169;20689.89655051715;20721.68917466239;20734.896325518275;8859.29125669945;8936.106389361012;9070.773230669154;9073.77315567103;9171.312151588658;4922.261660750916;5006.924896126561;5033.6476446648385;5051.747412629354;5229.320188375486;3412.3175364927115;3510.104923244556;3512.7014203792965;3517;3529.5411596492804;610.8656095658948;612.653850574424;632.7690393006519;635.0632816595485;639.750497306051;617.2562062714418;675.4292622733782;696.9477289203229;698.322627051759;698.937095661393;9253;9453.480058596706;9710.320277580502;9724.659636912675;10580.87471612228;609.3479976425183;628.4752231886426;694.9408307521253;789.7749141494646;818.2472125644422;4487.596116349544;4889.779959901813;5027.461132128252;5075.923861142086;5153.3815942087185;72136.63931680412;73086.63456682682;81809;98140.50929745448;121704.39147804205;32692.83653581717;32794.18014549655;56545.43454565484;65428.672856635414;95338;54308.728456357465;55336.72331638316;56204.71897640486;86834;88999;9602;9857.408555486694;10170.186385089322;10203.489825508546;10304.175665946856;47868.521314786405;53692;55331;56058.71970640121;57106.14340784808;79395;79871;81261;81728.59135704284;84278.73581896153;12295;12816;13439.596812095653;13470;13677.79483307771;14856.702865942836;14861;15207.315670795062;15296.46462373856;15558.222088895283;13265.801012985006;13642.795358069836;14102.153870767786;14337.211453370164;14504.129752214902;13213.669658258621;13953.511627093003;14016.018878678395;14153.858461415253;14345.713085738018;13047.217166970013;13610.251436171106;13789.51736689211;13832.170069795844;13873.4450621974;11436.82844757346;11474.4262786862;11554.537818487166;11852.229605075643;12100.515979360727;11770.411479425824;11793.705157371134;11937;11990.820137697767;12027.699307517381 23:30:36 INFO - PROCESS | 12824 | __end_tp_report 23:30:36 INFO - PROCESS | 12824 | __start_cc_report 23:30:36 INFO - PROCESS | 12824 | _x_x_mozilla_cycle_collect,872 23:30:36 INFO - PROCESS | 12824 | __end_cc_report 23:30:36 INFO - PROCESS | 12824 | __startTimestamp1469601036437__endTimestamp 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | ------- Summary: start ------- 23:30:36 INFO - PROCESS | 12824 | Number of tests: 6 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#0] dojo.html Cycles:165 Average:5376.89 Median:5533.86 stddev:623.56 (11.3%) stddev-sans-first:598.76 23:30:36 INFO - PROCESS | 12824 | Values: 3075.6 3081.8 3106.6 3112.0 3118.1 5291.5 5298.7 5355.6 5399.1 5409.2 5342.9 5376.0 5382.8 5421.5 5441.2 4812.3 4887.2 4916.1 4929.5 5107.8 4988.1 5004.1 5040.1 5051.6 5056.5 4816.8 4832.1 4853.9 4860.1 4968.6 4887.0 4897.6 4901.5 4902.7 4970.2 4918.1 4952.8 4959.8 4959.8 4978.7 5262.1 5391.4 5440.6 5445.8 5508.3 5845.6 5859.1 5911.4 5916.8 5921.3 5865.7 5913.5 5955.3 5961.7 5964.8 5269.4 5373.2 5427.4 5429.2 5499.5 5944.8 6001.4 6009.1 6010.5 6011.2 5727.9 5767.9 5779.6 5780.2 5783.6 5623.7 5727.2 5779.8 5781.9 5884.9 5795.0 5800.4 5802.7 5808.3 5817.6 5749.4 5751.2 5818.0 5818.4 5819.6 4296.9 4314.7 4348.3 4393.2 4394.9 3827.2 3926.4 3976.6 3994.9 4000.0 5475.6 5480.5 5482.0 5521.1 5533.9 5798.7 5804.8 5805.6 5808.0 5845.4 5241.1 5250.4 5381.9 5436.9 5437.2 4984.9 4988.0 4988.2 4998.6 5040.9 5971.1 5981.1 5983.3 5984.4 5986.8 5540.8 5743.7 5783.3 5794.7 5800.2 6011.1 6015.2 6016.3 6047.9 6075.1 5677.2 5723.7 5724.1 5728.4 5729.7 5640.3 5711.0 5722.8 5819.0 5832.2 5641.8 5650.9 5655.3 5680.7 5702.3 5628.0 5643.4 5653.4 5655.5 5659.3 5463.3 5475.9 5485.4 5591.6 5595.0 5625.8 5655.7 5658.9 5668.8 5671.8 5462.9 5467.0 5504.5 5514.6 5532.9 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#1] ext.html Cycles:180 Average:50295.02 Median:42833.07 stddev:87330.25 (203.9%) stddev-sans-first:87495.48 23:30:36 INFO - PROCESS | 12824 | Values: 586.7 622.1 624.4 634.1 640.3 36133.0 36773.1 45704.8 46089.8 46547.8 46840.5 47243.8 47298.3 47342.0 47365.3 9095.9 9253.1 9253.3 9258.9 9264.0 9079.4 9164.9 9166.9 9170.6 9170.6 9198.8 9199.6 9253.6 9254.1 9261.8 9109.5 9159.5 9172.0 9178.0 9180.4 9003.1 9133.4 9177.9 9180.0 9185.4 44563.8 44893.6 44939.6 45008.8 45115.8 3528.9 3674.8 3714.2 3750.5 3795.8 33881.2 34521.3 34533.1 34681.0 34744.3 4375.8 4442.0 4442.7 4444.1 4582.5 356971.0 360523.0 362392.0 363545.0 363790.0 60806.7 61285.0 61319.7 61323.7 61415.0 63005.7 63361.7 63647.0 63652.7 63724.7 17168.3 17301.5 17419.9 17426.9 17436.7 13240.7 13328.7 13336.4 13347.2 13349.5 16256.4 17616.6 17676.8 17703.4 17713.0 6284.8 6409.6 6435.7 6443.8 6447.3 963.5 1083.8 1101.7 1168.4 1178.9 32349.4 32422.2 32874.3 32877.2 33582.3 1094.2 1106.3 1145.3 1154.8 1181.8 17012.1 17026.7 17197.3 17335.6 17357.9 141722.0 141823.3 142167.3 143178.0 143366.0 62065.0 62397.4 62434.7 62439.0 62460.7 1250.6 1277.5 1277.9 1335.2 1365.6 25762.9 33899.8 34181.0 34345.3 34422.0 52157.0 52594.2 52745.2 52787.7 52804.0 416861.0 419601.0 419957.0 420408.0 420738.0 34178.8 35743.3 42804.0 42846.4 43779.6 48500.4 49779.7 49838.8 50700.0 51085.2 42819.8 43634.8 43751.1 44533.0 45750.5 42121.2 43222.0 43562.6 43622.3 43625.8 36117.0 38027.0 38117.4 40460.0 41705.6 37223.0 38482.2 38976.0 39602.4 39625.6 28085.7 28135.8 28469.1 28513.3 29916.0 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#2] jquery.html Cycles:180 Average:29224.14 Median:26260.41 stddev:73981.11 (281.7%) stddev-sans-first:74156.91 23:30:36 INFO - PROCESS | 12824 | Values: 360.2 389.6 400.5 401.7 419.4 14376.2 17350.7 19768.1 20238.8 20242.5 19545.8 19573.6 19692.4 19776.7 19839.3 5690.8 5826.3 5826.5 5949.6 5984.4 5804.9 5882.0 5892.4 5892.6 5914.8 5642.8 5761.9 5804.2 5870.0 5873.2 5723.7 5749.3 5757.2 5761.3 5768.1 4963.2 5092.4 5143.6 5199.6 5207.4 23297.8 23405.8 25662.4 26208.7 26312.1 2457.4 2572.4 2602.0 2607.4 3296.4 16284.1 16386.9 16423.9 16453.5 17291.2 2040.2 2057.6 2063.5 2141.9 2235.5 67248.0 74643.0 76270.2 78931.0 99702.5 14683.6 17292.6 18248.5 18423.5 18555.7 32114.5 32139.7 32150.0 32159.2 32204.7 10080.6 10120.9 10287.9 10367.3 10454.7 8078.0 8085.7 8323.1 8421.5 8442.1 9006.3 9087.9 9089.0 9133.2 9331.9 3394.4 3410.7 3474.2 3496.9 3513.3 928.2 928.4 944.9 956.0 1027.6 19039.7 20090.3 20245.6 20248.6 20251.0 1019.8 1034.4 1053.2 1057.6 1089.1 10411.4 11182.8 11229.3 11365.0 11367.4 385117.0 389111.0 390924.0 519783.0 522471.0 67454.3 102313.0 104845.0 105687.0 105971.0 909.5 1012.6 1027.1 1029.2 1030.1 18138.8 18187.5 18648.1 18675.0 18748.7 44095.0 45368.1 45884.4 46220.3 47772.3 63674.7 64001.0 64657.7 65151.3 66371.0 8285.3 8392.4 8412.0 8471.8 8501.6 12766.4 12881.5 13204.3 13838.4 14396.7 11474.4 11906.2 11935.5 12089.9 12328.2 11933.1 11987.6 12079.8 12091.3 12228.0 11233.3 11299.7 11821.5 11932.3 12111.4 8193.9 8515.7 8571.9 8588.1 8672.6 8578.4 11044.7 11348.1 11442.2 11511.8 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#3] mootools.html Cycles:180 Average:5970.86 Median:2466.84 stddev:3445.12 (139.7%) stddev-sans-first:3437.71 23:30:36 INFO - PROCESS | 12824 | Values: 1407.9 1493.8 1582.2 1691.9 1766.4 8743.8 9441.9 9462.6 9468.0 9471.7 7838.3 8591.7 8754.1 8805.3 9274.0 2397.2 2429.6 2431.6 2432.8 2433.3 2416.3 2439.5 2466.9 2469.8 2471.9 2438.0 2443.2 2448.0 2465.9 2468.2 2372.0 2375.9 2384.7 2392.4 2433.5 2331.1 2336.3 2337.3 2340.6 2341.7 9152.7 9286.0 9404.8 9415.8 9416.9 5503.2 5647.9 5663.0 5663.1 5669.2 3368.3 3538.6 3641.9 3686.0 3710.5 1082.8 1084.1 1093.4 1096.7 1115.7 13020.5 13061.5 13124.3 13136.3 13194.7 4486.5 4662.4 4781.8 4815.6 4818.4 10115.7 10186.6 10196.8 10203.4 10204.6 5541.3 5547.4 5548.9 5551.0 5551.9 4489.1 4881.9 4950.1 5009.2 5189.6 3875.6 3911.9 3979.4 4031.9 4036.9 1142.4 1147.1 1147.6 1150.6 1152.5 848.8 970.0 977.1 981.2 1024.9 6071.8 6089.5 6138.2 6285.6 6377.1 423.1 426.5 428.3 437.4 447.2 4062.3 4301.0 4310.1 4324.7 4371.6 10988.7 11241.9 11305.7 11319.7 11322.6 10045.3 10312.6 10330.8 11257.9 11265.0 8497.4 8677.2 8683.5 8687.5 8691.1 5935.2 6022.1 6048.0 6087.1 6201.4 10473.3 10774.1 10781.6 10855.6 10872.7 10568.3 10727.9 11131.3 11140.7 11142.4 7342.7 7844.6 7994.8 8104.8 8228.1 8679.3 8838.5 8859.7 8863.2 8868.3 7736.3 7738.2 7933.3 7955.2 8014.1 7465.5 7581.5 7582.7 7730.6 7860.8 6912.7 7056.6 7057.3 7127.6 7135.4 5199.7 5282.4 5322.8 5351.4 5516.8 5290.6 5309.6 5310.7 5353.6 5442.2 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#4] prototype.html Cycles:180 Average:4696.11 Median:5359.48 stddev:1378.36 (25.7%) stddev-sans-first:1342.44 23:30:36 INFO - PROCESS | 12824 | Values: 315.2 320.7 324.6 327.1 331.0 5345.4 5397.6 5409.8 5420.3 5431.2 5061.8 5150.9 5193.6 5195.5 5363.3 4042.3 4080.7 4144.7 4146.6 4178.3 4152.8 4173.9 4189.5 4190.8 4195.5 4065.5 4122.6 4128.1 4154.1 4159.2 4118.5 4135.4 4143.8 4148.8 4159.3 3680.8 3684.2 3690.2 3692.6 3710.5 5095.5 5226.8 5232.3 5240.6 5242.0 4655.4 4671.7 4673.6 4712.8 4759.2 5449.7 5463.3 5484.8 5500.9 5513.9 3918.2 3974.0 3984.9 4009.8 4013.8 5689.3 5693.5 5706.5 5715.6 5738.2 4113.2 4114.3 4151.8 4156.4 4160.0 4033.1 4202.2 4204.7 4209.6 4214.5 4678.2 4682.3 4693.6 4749.1 4750.0 5577.4 5581.9 5599.4 5613.4 5635.4 1330.8 1431.3 1456.7 1456.7 1457.5 918.3 950.6 960.3 971.2 984.0 5306.5 5311.7 5315.6 5332.9 5355.7 5511.8 5704.4 5721.9 5733.2 5745.9 5237.6 5258.3 5282.2 5304.2 5315.7 2979.2 3012.2 3014.6 3020.0 3033.1 5646.9 5792.7 5809.8 5893.4 5915.6 5722.0 5743.8 5749.1 5749.6 5786.6 5823.6 5852.3 5861.0 5865.7 5870.1 5439.4 5724.7 5753.7 5800.3 5814.4 5689.4 5700.5 5701.9 5732.6 5766.5 5745.8 5893.4 5955.9 5981.3 6009.3 4052.6 4065.3 4066.5 4079.7 4094.1 5827.2 5843.3 5868.6 5875.8 5876.6 5437.0 5531.5 5599.3 5636.7 5640.5 5625.3 5629.0 5631.6 5631.9 5717.2 5344.9 5431.5 5493.0 5641.8 5656.9 5490.7 5546.0 5625.3 5634.8 5636.8 5459.6 5493.3 5495.5 5507.9 5542.5 23:30:36 INFO - PROCESS | 12824 | 23:30:36 INFO - PROCESS | 12824 | [#5] yui.html Cycles:180 Average:18882.83 Median:4964.59 stddev:25405.39 (511.7%) stddev-sans-first:25440.25 23:30:36 INFO - PROCESS | 12824 | Values: 770.4 775.6 790.9 815.8 867.6 14452.9 15053.5 15169.0 15202.5 15232.2 14521.4 14658.3 15233.1 15334.2 15349.9 809.8 821.4 823.9 824.2 824.3 688.6 793.2 797.6 798.2 825.6 866.0 866.9 870.1 870.2 870.3 859.8 864.9 866.6 866.8 868.7 511.6 599.9 612.3 615.6 629.9 14800.8 14824.0 14830.2 14837.9 14868.5 14339.6 14439.4 14940.9 14964.1 15214.5 12594.4 12793.2 12857.4 12981.2 13712.9 4256.1 4272.3 4294.7 4306.4 4335.1 76619.6 77609.2 78870.2 79201.6 79921.6 11657.8 11686.2 11771.5 11853.6 12035.9 20139.8 20615.8 20689.9 20721.7 20734.9 8859.3 8936.1 9070.8 9073.8 9171.3 4922.3 5006.9 5033.6 5051.7 5229.3 3412.3 3510.1 3512.7 3517.0 3529.5 610.9 612.7 632.8 635.1 639.8 617.3 675.4 696.9 698.3 698.9 9253.0 9453.5 9710.3 9724.7 10580.9 609.3 628.5 694.9 789.8 818.2 4487.6 4889.8 5027.5 5075.9 5153.4 72136.6 73086.6 81809.0 98140.5 121704.4 32692.8 32794.2 56545.4 65428.7 95338.0 54308.7 55336.7 56204.7 86834.0 88999.0 9602.0 9857.4 10170.2 10203.5 10304.2 47868.5 53692.0 55331.0 56058.7 57106.1 79395.0 79871.0 81261.0 81728.6 84278.7 12295.0 12816.0 13439.6 13470.0 13677.8 14856.7 14861.0 15207.3 15296.5 15558.2 13265.8 13642.8 14102.2 14337.2 14504.1 13213.7 13953.5 14016.0 14153.9 14345.7 13047.2 13610.3 13789.5 13832.2 13873.4 11436.8 11474.4 11554.5 11852.2 12100.5 11770.4 11793.7 11937.0 11990.8 12027.7 23:30:36 INFO - PROCESS | 12824 | -------- Summary: end -------- 23:30:36 INFO - PROCESS | 12824 | 23:30:37 INFO - TEST-INFO | 12824: exit 0 23:30:37 INFO - TEST-OK | dromaeo_css | took 1114035ms 23:30:37 INFO - TEST-START | kraken 23:30:37 INFO - operating with platform_type : linux_ 23:30:37 INFO - Initialising browser for kraken test... 23:30:37 INFO - TEST-INFO | started process 13018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYyDiP7/profile http://localhost:51930/getInfo.html) 23:30:40 INFO - PROCESS | 13018 | JavaScript error: , line 0: uncaught exception: undefined 23:30:41 INFO - PROCESS | 13018 | __metrics Screen width/height:1600/1200 23:30:41 INFO - PROCESS | 13018 | colorDepth:24 23:30:41 INFO - PROCESS | 13018 | Browser inner width/height: 1024/697 23:30:41 INFO - PROCESS | 13018 | __metrics 23:30:42 INFO - PROCESS | 13018 | 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" 23:30:42 INFO - PROCESS | 13018 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:30:43 INFO - TEST-INFO | 13018: exit 0 23:30:43 INFO - Browser initialized. 23:30:43 INFO - Running cycle 1/1 for kraken test... 23:30:43 INFO - TEST-INFO | started process 13097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYyDiP7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:30:55 INFO - PROCESS | 13097 | RSS: Main: 158253056 23:30:55 INFO - PROCESS | 13097 | 23:30:57 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51930/tests/kraken/driver.html?testName=audio-beat-detection) 23:30:58 INFO - PROCESS | 13097 | RSS: Main: 214327296 23:30:58 INFO - PROCESS | 13097 | 23:31:01 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51930/tests/kraken/driver.html?testName=audio-dft) 23:31:01 INFO - PROCESS | 13097 | RSS: Main: 192618496 23:31:01 INFO - PROCESS | 13097 | 23:31:04 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51930/tests/kraken/driver.html?testName=audio-fft) 23:31:05 INFO - PROCESS | 13097 | RSS: Main: 270000128 23:31:05 INFO - PROCESS | 13097 | 23:31:07 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51930/tests/kraken/driver.html?testName=audio-oscillator) 23:31:08 INFO - PROCESS | 13097 | RSS: Main: 181059584 23:31:08 INFO - PROCESS | 13097 | 23:31:10 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51930/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:31:10 INFO - PROCESS | 13097 | RSS: Main: 186044416 23:31:10 INFO - PROCESS | 13097 | 23:31:13 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51930/tests/kraken/driver.html?testName=imaging-darkroom) 23:31:14 INFO - PROCESS | 13097 | RSS: Main: 221728768 23:31:14 INFO - PROCESS | 13097 | 23:31:17 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51930/tests/kraken/driver.html?testName=imaging-desaturate) 23:31:18 INFO - PROCESS | 13097 | RSS: Main: 220635136 23:31:18 INFO - PROCESS | 13097 | 23:31:21 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51930/tests/kraken/driver.html?testName=json-parse-financial) 23:31:21 INFO - PROCESS | 13097 | RSS: Main: 220168192 23:31:21 INFO - PROCESS | 13097 | 23:31:23 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51930/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:31:23 INFO - PROCESS | 13097 | RSS: Main: 185044992 23:31:23 INFO - PROCESS | 13097 | 23:31:25 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:31:26 INFO - PROCESS | 13097 | RSS: Main: 189980672 23:31:26 INFO - PROCESS | 13097 | 23:31:28 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:31:29 INFO - PROCESS | 13097 | RSS: Main: 195801088 23:31:29 INFO - PROCESS | 13097 | 23:31:31 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:31:32 INFO - PROCESS | 13097 | RSS: Main: 188645376 23:31:32 INFO - PROCESS | 13097 | 23:31:34 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:31:35 INFO - PROCESS | 13097 | RSS: Main: 193462272 23:31:35 INFO - PROCESS | 13097 | 23:31:36 INFO - PROCESS | 13097 | Cycle 1(1): loaded http://localhost:51930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51930/tests/kraken/driver.html?testName=ai-astar) 23:31:37 INFO - PROCESS | 13097 | RSS: Main: 244695040 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | __start_tp_report 23:31:37 INFO - PROCESS | 13097 | _x_x_mozilla_page_load 23:31:37 INFO - PROCESS | 13097 | _x_x_mozilla_page_load_details 23:31:37 INFO - PROCESS | 13097 | |i|pagename|runs| 23:31:37 INFO - PROCESS | 13097 | |0;ai-astar;103;113;111;110;110;112;109;113;110;110 23:31:37 INFO - PROCESS | 13097 | |1;audio-beat-detection;149;116;120;172;118;118;139;116;152;122 23:31:37 INFO - PROCESS | 13097 | |2;audio-dft;163;160;161;162;162;161;161;162;162;161 23:31:37 INFO - PROCESS | 13097 | |3;audio-fft;74;76;74;131;75;75;74;75;107;75 23:31:37 INFO - PROCESS | 13097 | |4;audio-oscillator;83;83;111;85;83;84;121;83;85;133 23:31:37 INFO - PROCESS | 13097 | |5;imaging-gaussian-blur;116;117;117;117;116;117;117;117;117;116 23:31:37 INFO - PROCESS | 13097 | |6;imaging-darkroom;145;146;146;166;146;146;146;145;146;146 23:31:37 INFO - PROCESS | 13097 | |7;imaging-desaturate;96;95;94;95;94;95;94;95;94;94 23:31:37 INFO - PROCESS | 13097 | |8;json-parse-financial;51;50;50;50;51;52;50;54;50;49 23:31:37 INFO - PROCESS | 13097 | |9;json-stringify-tinderbox;62;62;63;63;63;62;63;62;63;63 23:31:37 INFO - PROCESS | 13097 | |10;stanford-crypto-aes;76;79;75;78;75;74;77;75;74;78 23:31:37 INFO - PROCESS | 13097 | |11;stanford-crypto-ccm;126;124;121;119;119;127;126;122;163;124 23:31:37 INFO - PROCESS | 13097 | |12;stanford-crypto-pbkdf2;153;152;159;160;153;153;158;157;154;152 23:31:37 INFO - PROCESS | 13097 | |13;stanford-crypto-sha256-iterative;64;62;63;63;61;63;62;61;60;60 23:31:37 INFO - PROCESS | 13097 | __end_tp_report 23:31:37 INFO - PROCESS | 13097 | __start_cc_report 23:31:37 INFO - PROCESS | 13097 | _x_x_mozilla_cycle_collect,1667 23:31:37 INFO - PROCESS | 13097 | __end_cc_report 23:31:37 INFO - PROCESS | 13097 | __startTimestamp1469601097240__endTimestamp 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | ------- Summary: start ------- 23:31:37 INFO - PROCESS | 13097 | Number of tests: 14 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:2.85 (2.6%) stddev-sans-first:1.45 23:31:37 INFO - PROCESS | 13097 | Values: 103.0 113.0 111.0 110.0 110.0 112.0 109.0 113.0 110.0 110.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:130.50 stddev:19.68 (15.1%) stddev-sans-first:19.91 23:31:37 INFO - PROCESS | 13097 | Values: 149.0 116.0 120.0 172.0 118.0 118.0 139.0 116.0 152.0 122.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.71 23:31:37 INFO - PROCESS | 13097 | Values: 163.0 160.0 161.0 162.0 162.0 161.0 161.0 162.0 162.0 161.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#3] audio-fft Cycles:10 Average:83.60 Median:75.00 stddev:19.51 (26.0%) stddev-sans-first:20.38 23:31:37 INFO - PROCESS | 13097 | Values: 74.0 76.0 74.0 131.0 75.0 75.0 74.0 75.0 107.0 75.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:83.00 stddev:19.07 (23.0%) stddev-sans-first:19.72 23:31:37 INFO - PROCESS | 13097 | Values: 83.0 83.0 111.0 85.0 83.0 84.0 121.0 83.0 85.0 133.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 23:31:37 INFO - PROCESS | 13097 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:146.00 stddev:6.41 (4.4%) stddev-sans-first:6.72 23:31:37 INFO - PROCESS | 13097 | Values: 145.0 146.0 146.0 166.0 146.0 146.0 146.0 145.0 146.0 146.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 23:31:37 INFO - PROCESS | 13097 | Values: 96.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.50 stddev:1.42 (2.8%) stddev-sans-first:1.50 23:31:37 INFO - PROCESS | 13097 | Values: 51.0 50.0 50.0 50.0 51.0 52.0 50.0 54.0 50.0 49.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 23:31:37 INFO - PROCESS | 13097 | Values: 62.0 62.0 63.0 63.0 63.0 62.0 63.0 62.0 63.0 63.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.50 stddev:1.79 (2.3%) stddev-sans-first:1.90 23:31:37 INFO - PROCESS | 13097 | Values: 76.0 79.0 75.0 78.0 75.0 74.0 77.0 75.0 74.0 78.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:125.00 stddev:12.93 (10.3%) stddev-sans-first:13.71 23:31:37 INFO - PROCESS | 13097 | Values: 126.0 124.0 121.0 119.0 119.0 127.0 126.0 122.0 163.0 124.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:155.50 stddev:3.07 (2.0%) stddev-sans-first:3.16 23:31:37 INFO - PROCESS | 13097 | Values: 153.0 152.0 159.0 160.0 153.0 153.0 158.0 157.0 154.0 152.0 23:31:37 INFO - PROCESS | 13097 | 23:31:37 INFO - PROCESS | 13097 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.50 stddev:1.37 (2.2%) stddev-sans-first:1.22 23:31:37 INFO - PROCESS | 13097 | Values: 64.0 62.0 63.0 63.0 61.0 63.0 62.0 61.0 60.0 60.0 23:31:37 INFO - PROCESS | 13097 | -------- Summary: end -------- 23:31:37 INFO - PROCESS | 13097 | 23:31:38 INFO - TEST-INFO | 13097: exit 0 23:31:38 INFO - TEST-OK | kraken | took 61140ms 23:31:38 INFO - SUITE-END | took 1175s 23:31:38 INFO - Completed test suite (00:19:35) 23:31:38 INFO - javascript benchmark 23:31:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.5694202811605, 3081.784275100741, 3106.64273608535, 3112.0041586692255, 3118.1269244611503, 5291.470852914711, 5298.682079075248, 5355.598330125246, 5399.109146990747, 5409.188621706741, 5342.91985620216, 5375.973120134404, 5382.757775900084, 5421.457854214576, 5441.183822426638, 4812.326274321597, 4887.218045112782, 4916.115099282128, 4929.4824043475455, 5107.846764597068, 4988.102141614508, 5004.12427825131, 5040.143175660129, 5051.595872330205, 5056.469070747574, 4816.83141090062, 4832.057748738997, 4853.927191092136, 4860.1008813369635, 4968.577670897978, 4887.0, 4897.583705385046, 4901.509849015106, 4902.730349830758, 4970.168044227936, 4918.090153321611, 4952.752362381866, 4959.752012399365, 4959.776810043557, 4978.676386034932, 5262.1317482615705, 5391.353037635469, 5440.619156659035, 5445.809396671137, 5508.3114610673665, 5845.649261044308, 5859.091840764689, 5911.379305172981, 5916.763329466816, 5921.259842519685, 5865.736041878125, 5913.497352725023, 5955.285365756094, 5961.701914904281, 5964.7912323068485, 5269.420363759983, 5373.247745315659, 5427.43011983744, 5429.212764149176, 5499.50504454601, 5944.82165535026, 6001.36985616504, 6009.06859436788, 6010.4891084258325, 6011.248593925759, 5727.8854422912145, 5767.913481297784, 5779.595428319977, 5780.219670344533, 5783.566232532577, 5623.718814059281, 5727.226824378656, 5779.797707080232, 5781.8554536136935, 5884.941150588525, 5794.971025144847, 5800.390958949334, 5802.738876750556, 5808.332041815161, 5817.592768506164, 5749.425057494301, 5751.223584815996, 5818.040023396177, 5818.359980402237, 5819.621724587888, 4296.914061718748, 4314.69797114199, 4348.326009468537, 4393.1872603568445, 4394.868153955386, 3827.2345530893376, 3926.3521518949624, 3976.622220889011, 3994.900127496836, 3999.960000399959, 5475.561955043587, 5480.451954804487, 5482.0, 5521.116621340566, 5533.861653458615, 5798.739056742456, 5804.79683211094, 5805.593608447368, 5807.970960145172, 5845.386234445407, 5241.109011467984, 5250.422453530108, 5381.946180538144, 5436.864078398072, 5437.157240627709, 4984.90030199394, 4987.975060124749, 4988.201887697951, 4998.575121114746, 5040.949590504121, 5971.104334349795, 5981.0729336952845, 5983.311919129356, 5984.401559844068, 5986.790462333797, 5540.750666219949, 5743.655380677173, 5783.277090363705, 5794.681292528951, 5800.24596802413, 6011.128386384037, 6015.248093988252, 6016.338202797776, 6047.879042419215, 6075.088736689355, 5677.205191273308, 5723.656580605177, 5724.112762521816, 5728.398518155534, 5729.742161602655, 5640.32316122068, 5711.0, 5722.7710891563875, 5818.970905145447, 5832.154337621106, 5641.774329026957, 5650.943490565041, 5655.321361436654, 5680.715964201856, 5702.315722113373, 5628.0, 5643.407442218508, 5653.434656534314, 5655.462731040545, 5659.320881494247, 5463.31708536433, 5475.890482190413, 5485.424030476902, 5591.6365436246515, 5595.04884169684, 5625.774969001194, 5655.7172141393585, 5658.868226354663, 5668.773249069991, 5671.801486947937, 5462.945370546403, 5466.972665136808, 5504.504594586507, 5514.5864060194845, 5532.889342213052], "value": 5333.828155859636, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.6861229242356, 622.1197006236176, 624.4317670919464, 634.1407392982509, 640.3052687833706, 36133.0, 36773.08067298315, 45704.771476142654, 46089.769551152196, 46547.767261163644, 46840.53159468404, 47243.76378118105, 47298.29052564214, 47342.0, 47365.289520657134, 9095.863562046574, 9253.074692530727, 9253.259739220846, 9258.861117083248, 9263.953680231589, 9079.409838360518, 9164.8625270621, 9166.908330916705, 9170.587323570455, 9170.633174673005, 9198.816023679556, 9199.632014719436, 9253.58358873849, 9254.074592540726, 9261.768455788591, 9109.544522773867, 9159.542022898895, 9172.0, 9177.954110229473, 9180.403273787217, 9003.09969003101, 9133.40632858862, 9177.908220917772, 9180.03609620987, 9185.448873067637, 44563.77718111421, 44893.551064489264, 44939.55060449387, 45008.77495612534, 45115.774421128015, 3528.8721369668956, 3674.761140525857, 3714.182879766447, 3750.474933509311, 3795.8291876865333, 33881.152971175674, 34521.309573808634, 34533.13667158316, 34681.0, 34744.30511389783, 4375.803088861009, 4442.0, 4442.689011769177, 4444.066745983348, 4582.518835522255, 356971.0, 360523.0, 362392.0, 363545.0, 363790.0, 60806.69596651988, 61285.0, 61319.693401532706, 61323.69338153281, 61415.0, 63005.68497157485, 63361.68319158375, 63647.0, 63652.68173659102, 63724.681376592824, 17168.313267469162, 17301.4809555711, 17419.912900435418, 17426.91286543559, 17436.651266974844, 13240.668983275302, 13328.666783330495, 13336.399862006234, 13347.199168049949, 13349.46602135923, 16256.43102491407, 17616.64766704659, 17676.823231767517, 17703.38038168658, 17713.0, 6284.842878928063, 6409.647469389229, 6435.710393032325, 6443.774467893695, 6447.323031081763, 963.4797209506788, 1083.8049151152868, 1101.7080473674482, 1168.4449886303937, 1178.9469473873696, 32349.35301293961, 32422.189445264055, 32874.342513149604, 32877.17807054843, 33582.32835343328, 1094.2285688589525, 1106.319613437746, 1145.2613064573304, 1154.7690461907653, 1181.8091153884545, 17012.064336461364, 17026.659466810594, 17197.312107515558, 17335.56661083458, 17357.913210433868, 141722.0, 141823.29088354492, 142167.2891635556, 143178.0, 143366.0, 62065.0, 62397.37602623916, 62434.68782656058, 62439.0, 62460.687696561225, 1250.6373151786063, 1277.4826195390697, 1277.8849903508728, 1335.152178366744, 1365.6449323175848, 25762.87118564395, 33899.83050084734, 34181.0, 34345.313093738485, 34422.0, 52156.956860861814, 52594.21108683449, 52745.20882186694, 52787.73606131945, 52804.0, 416861.0, 419601.0, 419957.0, 420408.0, 420738.0, 34178.82910585431, 35743.28513429769, 42804.0, 42846.35730463983, 43779.56220437755, 48500.39873790795, 49779.73348500008, 49838.75080624719, 50700.0, 51085.23372149495, 42819.785901070296, 43634.781826090664, 43751.124977500906, 44533.0, 45750.54249457596, 42121.157576847676, 43222.0, 43562.55950491877, 43622.34566481442, 43625.78187109044, 36117.0, 38027.0, 38117.428238577, 40460.0, 41705.58294417139, 37223.0, 38482.230355393294, 38976.0, 39602.40596390999, 39625.603743963344, 28085.71914280831, 28135.81712265204, 28469.145925622266, 28513.28716782014, 29916.0], "value": 20024.21534585044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.23629904602245, 389.6162280154048, 400.5113761211322, 401.7248184993279, 419.4107279272621, 14376.209308488029, 17350.65298694027, 19768.110435030423, 20238.797612023893, 20242.49393765153, 19545.804541954578, 19573.608527829434, 19692.40922772319, 19776.70334944977, 19839.305624303153, 5690.829275121732, 5826.300843898727, 5826.475617194452, 5949.613275137124, 5984.431479009487, 5804.912926306109, 5881.970590147043, 5892.351841297454, 5892.616979896315, 5914.822555323347, 5642.7742890284335, 5761.913571296413, 5804.245448091742, 5869.9706501467435, 5873.236479257691, 5723.713814309289, 5749.252597162342, 5757.165211044396, 5761.279840019997, 5768.134779783045, 4963.2058870580895, 5092.388913330424, 5143.588512918957, 5199.558037566811, 5207.401148867891, 23297.76702232973, 23405.765942340528, 25662.358441038938, 26208.73791262082, 26312.079077232396, 2457.4347899983104, 2572.369769406507, 2601.9869900650565, 2607.4133320002848, 3296.428785547277, 16284.10437425941, 16386.918065409598, 16423.91788041052, 16453.506394808057, 17291.221895014754, 2040.2247146084542, 2057.5781964697203, 2063.484128967758, 2141.9464513387134, 2235.5305385868824, 67247.9999999995, 74643.0, 76270.20470803743, 78931.0, 99702.50148749282, 14683.60249440359, 17292.567685807957, 18248.543786405182, 18423.53941151455, 18555.72166417505, 32114.51828222532, 32139.678603214135, 32150.0, 32159.196020099684, 32204.67795322017, 10080.647177348757, 10120.937301583375, 10287.897121028844, 10367.32612380193, 10454.68635940923, 8077.959610202029, 8085.676572937016, 8323.1260717625, 8421.536815475085, 8442.113578074337, 9006.279497640173, 9087.863682044775, 9088.954555227314, 9133.178013978773, 9331.860022099674, 3394.4229480988292, 3410.6759857813468, 3474.2455060194943, 3496.9125771855906, 3513.332466831294, 928.2063835420753, 928.4429342394644, 944.9385789923633, 956.0200794185876, 1027.6197806811379, 19039.714404283946, 20090.296839610837, 20245.595088098155, 20248.59502809965, 20251.0, 1019.7858449725493, 1034.4155552113032, 1053.2311412668794, 1057.640402263219, 1089.128697042363, 10411.375317480824, 11182.832257516144, 11229.27009744364, 11365.0, 11367.431628418712, 385117.0, 389111.0, 390924.0, 519783.0, 522471.0, 67454.32545674579, 102313.0, 104845.0, 105687.0, 105971.0, 909.4815954905772, 1012.6354512375539, 1027.091024443358, 1029.2075102171436, 1030.0832259289239, 18138.81861181424, 18187.45437636873, 18648.067596620385, 18675.0, 18748.7187692182, 44095.0, 45368.092638146394, 45884.35947524462, 46220.306695398926, 47772.2834157481, 63674.68162659157, 64001.0, 64657.67671161802, 65151.34848651642, 66371.0, 8285.337173026264, 8392.412531123007, 8412.032616248965, 8471.830563388821, 8501.574921254036, 12766.42551085222, 12881.484740610646, 13204.27376494302, 13838.37727302294, 14396.712065758416, 11474.368909709712, 11906.166568340133, 11935.462904169506, 12089.939550302544, 12328.19866708661, 11933.105017123577, 11987.64037078889, 12079.818802717791, 12091.334976576372, 12228.0, 11233.32600044, 11299.717507062389, 11821.468033938665, 11932.343721095423, 12111.394430278628, 8193.918060819315, 8515.74452766418, 8571.871421928801, 8588.141185881612, 8672.609732561923, 8578.399512034348, 11044.723881903017, 11348.092152627605, 11442.199046066695, 11511.827322590336], "value": 10218.93302488197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.9440419685236, 1493.8431464696207, 1582.2168026826723, 1691.8561922236609, 1766.3729376071494, 8743.825123497534, 9441.905580944189, 9462.574184161713, 9468.0, 9471.668491602795, 7838.255365740246, 8591.742247732578, 8754.08082151373, 8805.295576353892, 9273.953630231856, 2397.220903206456, 2429.586970215059, 2431.6109422492405, 2432.7567243275707, 2433.3429973907123, 2416.2871952773976, 2439.499902519988, 2466.851988880664, 2469.814763892706, 2471.9011239550396, 2438.0, 2443.1571107967725, 2448.0085565345976, 2465.864377459239, 2468.1854987854053, 2372.027468737818, 2375.857448553092, 2384.654225137354, 2392.413858604653, 2433.549793288247, 2331.079223706626, 2336.3458231695154, 2337.2988103569032, 2340.625499920022, 2341.695579574661, 9152.725418237396, 9286.0, 9404.764880878032, 9415.811683766286, 9416.90583094167, 5503.229547863302, 5647.8588035299035, 5663.0, 5663.0655941769555, 5669.2346533218315, 3368.292658541709, 3538.575370955476, 3641.9271614567824, 3685.9631403686158, 3710.4619830124752, 1082.7617924056694, 1084.0568705226424, 1093.4423444043516, 1096.660035389032, 1115.7043383503378, 13020.544280950216, 13061.542846000344, 13124.343782810822, 13136.34318284082, 13194.736105277841, 4486.461624605068, 4662.393888794503, 4781.832635857798, 4815.614750819926, 4818.445878723922, 10115.747106322253, 10186.592536298613, 10196.847047294297, 10203.438810865326, 10204.591816327264, 5541.307336582927, 5547.389787123413, 5548.889022219533, 5551.028570000234, 5551.8889622207325, 4489.057297967398, 4881.877953051202, 4950.059488697136, 5009.198528235538, 5189.636725429184, 3875.6124387561017, 3911.9022024449046, 3979.422983667352, 4031.8992025199605, 4036.8788936331957, 1142.3774043146504, 1147.0880649883363, 1147.6442302886132, 1150.5972909481613, 1152.458344578047, 848.7835601921471, 969.9806003880024, 977.1010670183448, 981.1954197558076, 1024.8770147582338, 6071.787487437919, 6089.451949324493, 6138.202033735586, 6285.5914365567005, 6377.1390862233, 423.1452466018687, 426.4988638349988, 428.3467711739622, 437.4226021651453, 447.2262985035906, 4062.289099407592, 4301.0, 4310.137972405531, 4324.697271190987, 4371.562843715603, 10988.725281868017, 11241.887581124247, 11305.71735706614, 11319.660410187707, 11322.60370887015, 10045.29682922203, 10312.587496500057, 10330.793384132276, 11257.943710281395, 11265.0, 8497.447665901696, 8677.1756683115, 8683.478991260545, 8687.52218627969, 8691.087435819274, 5935.228420305505, 6022.126791615104, 6048.0, 6087.086936959491, 6201.379862013762, 10473.31923424975, 10774.138068954622, 10781.62264320745, 10855.620053298097, 10872.728181795519, 10568.313059651098, 10727.946360268148, 11131.332120072822, 11140.665780026613, 11142.442877856236, 7342.706291748484, 7844.6077696113825, 7994.840103197787, 8104.797380065545, 8228.136045715113, 8679.34904882149, 8838.513881736566, 8859.734207973772, 8863.246624037029, 8868.33487488428, 7736.342410894912, 7738.2261773822165, 7933.286004259414, 7955.20447955223, 8014.078380986261, 7465.477416580788, 7581.50720203185, 7582.734604288823, 7730.574818384818, 7860.764177074697, 6912.688928998106, 7056.6471676417, 7057.258987806206, 7127.572345659277, 7135.42916566663, 5199.66202196856, 5282.445343238905, 5322.813701520428, 5351.384590772111, 5516.806911758069, 5290.629655924048, 5309.601779866603, 5310.654807437504, 5353.571714262772, 5442.210879422382], "value": 4660.074269557794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.16324159356884, 320.6761171217071, 324.58777352761985, 327.0809026635154, 330.97352211823056, 5345.423100185445, 5397.649152805065, 5409.810656627019, 5420.268263784388, 5431.239626452295, 5061.822836200734, 5150.871228219296, 5193.584513238941, 5195.532402083812, 5363.275957745703, 4042.3128068228325, 4080.693947953908, 4144.668426525871, 4146.631611568176, 4178.289690752564, 4152.8131234094535, 4173.916521669565, 4189.549113768434, 4190.832366705328, 4195.496540415154, 4065.4714887064642, 4122.567130451304, 4128.1330920506725, 4154.106867023589, 4159.168166366723, 4118.464599602063, 4135.441715368447, 4143.834246630131, 4148.8340466381305, 4159.334506478979, 3680.7975561344115, 3684.171061511165, 3690.2435000824767, 3692.5568931728367, 3710.499082623838, 5095.490450954912, 5226.7909283628605, 5232.319798426218, 5240.633155679104, 5242.0, 4655.4180727409075, 4671.719696818202, 4673.649476289258, 4712.764361781931, 4759.214729569617, 5449.719315960746, 5463.344398672185, 5484.751251986706, 5500.889982200333, 5513.917291240635, 3918.196769662241, 3974.0, 3984.860529881469, 4009.7794621295816, 4013.7591744495426, 5689.288838895138, 5693.491224825425, 5706.543476521868, 5715.628484148517, 5738.167965644958, 4113.1568028554075, 4114.333694853589, 4151.750894946253, 4156.43888075112, 4160.0, 4033.1328764315226, 4202.243596152722, 4204.684648651363, 4209.5579964103945, 4214.452121224283, 4678.204705200124, 4682.321063445781, 4693.647976401783, 4749.145153872267, 4749.976250118796, 5577.358603760538, 5581.888362232733, 5599.412061733541, 5613.382527921925, 5635.408282130399, 1330.7804212304861, 1431.3272761801936, 1456.6649670575614, 1456.6940942402002, 1457.4898785425228, 918.2745630951473, 950.6482601437513, 960.3085778239656, 971.1987610221596, 983.9655612053543, 5306.46935306474, 5311.7344132793205, 5315.6279060465395, 5332.893507064688, 5355.678659280456, 5511.779528818883, 5704.401037891044, 5721.885562288731, 5733.219388596527, 5745.88508229833, 5237.607179461479, 5258.2638430619745, 5282.1548552232225, 5304.177852432879, 5315.681059136464, 2979.2402937250863, 3012.2469382654335, 3014.5779590857296, 3020.0486846643676, 3033.0597514770493, 5646.887062258732, 5792.681402522818, 5809.767609295665, 5893.381194974551, 5915.58590898633, 5721.971390143022, 5743.827685169368, 5749.13762935563, 5749.568782341257, 5786.566007549451, 5823.621464604787, 5852.268466441695, 5860.970695146497, 5865.736041878125, 5870.119482077637, 5439.374471935778, 5724.685142317213, 5753.683547404933, 5800.346901133157, 5814.418558144152, 5689.402612725769, 5700.543956483389, 5701.942980570141, 5732.598718089694, 5766.538676905921, 5745.770169193185, 5893.381194974638, 5955.880882382415, 5981.252343457068, 6009.308929473166, 4052.63526282636, 4065.329220678555, 4066.512018557674, 4079.73481723687, 4094.1197642507004, 5827.24245848037, 5843.328017278068, 5868.559858010581, 5875.764969401176, 5876.647401155944, 5437.0, 5531.474509921551, 5599.300087489064, 5636.689982051026, 5640.54875609942, 5625.268715067015, 5629.0, 5631.605787594993, 5631.915521267102, 5717.199592057199, 5344.919826202532, 5431.4840090191365, 5493.0, 5641.830745077655, 5656.943430565641, 5490.6980116094, 5546.0, 5625.268715067015, 5634.802781902614, 5636.830895073154, 5459.617826752089, 5493.2858728366655, 5495.477929596682, 5507.889842203053, 5542.501174894279], "value": 4253.895532887167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.4491288728558, 775.6121939030484, 790.8774140008297, 815.8042069903225, 867.5748883047306, 14452.855471445282, 15053.473128440506, 15169.01401408909, 15202.54392368228, 15232.162231077285, 14521.419143234258, 14658.340374683139, 15233.086014839117, 15334.156621385819, 15349.923250383761, 809.8339840332733, 821.36344333142, 823.8640624296984, 824.2375802382802, 824.2664029014182, 688.6178171115029, 793.1671744668105, 797.5852556670528, 798.1539568057848, 825.6160885188386, 865.965361385547, 866.8613021916494, 870.1429092344036, 870.1863757386832, 870.2646263906988, 859.7549698335944, 864.887564616599, 866.618687777376, 866.7833041739565, 868.7089824908663, 511.5856156513199, 599.865030368168, 612.2775125352083, 615.5660259517075, 629.8614304852971, 14800.777988330183, 14824.0, 14830.184339861304, 14837.851621483755, 14868.479603213944, 14339.641508962255, 14439.422423103064, 14940.850591494054, 14964.08619313645, 15214.46749363778, 12594.37028148589, 12793.232406055666, 12857.4214160363, 12981.156224845356, 13712.93143534276, 4256.063665993507, 4272.273713468718, 4294.742315461082, 4306.44016277885, 4335.067960388532, 76619.6169019157, 77609.22390776077, 78870.21129788685, 79201.60399198024, 79921.60039199825, 11657.825132623017, 11686.18196726238, 11771.470283837249, 11853.644390668122, 12035.939820300842, 20139.798602014085, 20615.79384206169, 20689.89655051715, 20721.68917466239, 20734.896325518275, 8859.29125669945, 8936.106389361012, 9070.773230669154, 9073.77315567103, 9171.312151588658, 4922.261660750916, 5006.924896126561, 5033.6476446648385, 5051.747412629354, 5229.320188375486, 3412.3175364927115, 3510.104923244556, 3512.7014203792965, 3517.0, 3529.5411596492804, 610.8656095658948, 612.653850574424, 632.7690393006519, 635.0632816595485, 639.750497306051, 617.2562062714418, 675.4292622733782, 696.9477289203229, 698.322627051759, 698.937095661393, 9253.0, 9453.480058596706, 9710.320277580502, 9724.659636912675, 10580.87471612228, 609.3479976425183, 628.4752231886426, 694.9408307521253, 789.7749141494646, 818.2472125644422, 4487.596116349544, 4889.779959901813, 5027.461132128252, 5075.923861142086, 5153.3815942087185, 72136.63931680412, 73086.63456682682, 81809.0, 98140.50929745448, 121704.39147804205, 32692.83653581717, 32794.18014549655, 56545.43454565484, 65428.672856635414, 95338.0, 54308.728456357465, 55336.72331638316, 56204.71897640486, 86834.0, 88999.0, 9602.0, 9857.408555486694, 10170.186385089322, 10203.489825508546, 10304.175665946856, 47868.521314786405, 53692.0, 55331.0, 56058.71970640121, 57106.14340784808, 79395.0, 79871.0, 81261.0, 81728.59135704284, 84278.73581896153, 12295.0, 12816.0, 13439.596812095653, 13470.0, 13677.79483307771, 14856.702865942836, 14861.0, 15207.315670795062, 15296.46462373856, 15558.222088895283, 13265.801012985006, 13642.795358069836, 14102.153870767786, 14337.211453370164, 14504.129752214902, 13213.669658258621, 13953.511627093003, 14016.018878678395, 14153.858461415253, 14345.713085738018, 13047.217166970013, 13610.251436171106, 13789.51736689211, 13832.170069795844, 13873.4450621974, 11436.82844757346, 11474.4262786862, 11554.537818487166, 11852.229605075643, 12100.515979360727, 11770.411479425824, 11793.705157371134, 11937.0, 11990.820137697767, 12027.699307517381], "value": 7497.0116601903155, "unit": "score"}], "name": "dromaeo_css", "value": 7385.030883580555, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 111.0, 110.0, 110.0, 112.0, 109.0, 113.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 120.0, 172.0, 118.0, 118.0, 139.0, 116.0, 152.0, 122.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 162.0, 162.0, 161.0, 161.0, 162.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 131.0, 75.0, 75.0, 74.0, 75.0, 107.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 111.0, 85.0, 83.0, 84.0, 121.0, 83.0, 85.0, 133.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 166.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 51.0, 52.0, 50.0, 54.0, 50.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 75.0, 78.0, 75.0, 74.0, 77.0, 75.0, 74.0, 78.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 124.0, 121.0, 119.0, 119.0, 127.0, 126.0, 122.0, 163.0, 124.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 159.0, 160.0, 153.0, 153.0, 158.0, 157.0, 154.0, 152.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 61.0, 63.0, 62.0, 61.0, 60.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1475.1, "alertThreshold": 2.0}]} 23:31:38 INFO - Return code: 0 23:31:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:31:38 INFO - # TBPL SUCCESS # 23:31:38 INFO - Running post-action listener: _resource_record_post_action 23:31:38 INFO - [mozharness: 2016-07-27 06:31:38.529852Z] Finished run-tests step (success) 23:31:38 INFO - Running post-run listener: _resource_record_post_run 23:31:38 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 563150848; Read time: 0; Write time: 537284 23:31:38 INFO - TinderboxPrint: CPU usage
13.0% 23:31:38 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 23:31:38 INFO - TinderboxPrint: I/O write bytes / time
563,150,848 / 537,284 23:31:38 INFO - TinderboxPrint: CPU idle
8,282.9 (87.0%) 23:31:38 INFO - TinderboxPrint: CPU user
1,191.9 (12.5%) 23:31:38 INFO - TinderboxPrint: Swap in / out
0 / 0 23:31:38 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 264822784; Read time: 0; Write time: 258496 23:31:38 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 294440960; Read time: 0; Write time: 278056 23:31:39 INFO - Running post-run listener: _upload_blobber_files 23:31:39 INFO - Blob upload gear active. 23:31:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:31:39 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 23:31:39 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:31:39 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:31:39 INFO - (blobuploader) - INFO - Open directory for files ... 23:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:31:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:40 INFO - (blobuploader) - INFO - Done attempting. 23:31:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:31:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:45 INFO - (blobuploader) - INFO - Done attempting. 23:31:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:31:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:31:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:46 INFO - (blobuploader) - INFO - Done attempting. 23:31:46 INFO - (blobuploader) - INFO - Iteration through files over. 23:31:46 INFO - Return code: 0 23:31:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:31:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:31:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/97733446d05809f7bc462866863981b735e1371fa2b2c758e0616223b39eb587e9bcc5affeea99cfc0082e27dd19bfedea469ab76953bde4d40afa8c6a3f0570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/86a0c278c2b66209ba5a7fb24d70ef82fb7759198b6bcf4d8b03012409e6169f69d9c8d2c055fdc1a629ee8942e3fe0a707bd032e70365d5b8f66a003802e044", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/55597ac4bd01308bd97a75a7d6dfbce0de1757fe717eb78783a843cdc052138cda1bf63b0265d41b79698cb7db225da705b57b306068d2b9c5e41c01bc0fba16"} 23:31:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:31:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:31:46 INFO - Contents: 23:31:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/97733446d05809f7bc462866863981b735e1371fa2b2c758e0616223b39eb587e9bcc5affeea99cfc0082e27dd19bfedea469ab76953bde4d40afa8c6a3f0570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/86a0c278c2b66209ba5a7fb24d70ef82fb7759198b6bcf4d8b03012409e6169f69d9c8d2c055fdc1a629ee8942e3fe0a707bd032e70365d5b8f66a003802e044", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/55597ac4bd01308bd97a75a7d6dfbce0de1757fe717eb78783a843cdc052138cda1bf63b0265d41b79698cb7db225da705b57b306068d2b9c5e41c01bc0fba16"} 23:31:46 INFO - Running post-run listener: copy_logs_to_upload_dir 23:31:46 INFO - Copying logs to upload dir... 23:31:46 INFO - mkdir: /builds/slave/test/build/upload/logs 23:31:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.116935 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-26 23:31:46.155195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 23:31:46.158479) ========= 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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 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/sha512/97733446d05809f7bc462866863981b735e1371fa2b2c758e0616223b39eb587e9bcc5affeea99cfc0082e27dd19bfedea469ab76953bde4d40afa8c6a3f0570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/86a0c278c2b66209ba5a7fb24d70ef82fb7759198b6bcf4d8b03012409e6169f69d9c8d2c055fdc1a629ee8942e3fe0a707bd032e70365d5b8f66a003802e044", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/55597ac4bd01308bd97a75a7d6dfbce0de1757fe717eb78783a843cdc052138cda1bf63b0265d41b79698cb7db225da705b57b306068d2b9c5e41c01bc0fba16"} build_url:https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011547 build_url: 'https://queue.taskcluster.net/v1/task/BM0a7PdPT5WqE-0GrqReAg/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/sha512/97733446d05809f7bc462866863981b735e1371fa2b2c758e0616223b39eb587e9bcc5affeea99cfc0082e27dd19bfedea469ab76953bde4d40afa8c6a3f0570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/86a0c278c2b66209ba5a7fb24d70ef82fb7759198b6bcf4d8b03012409e6169f69d9c8d2c055fdc1a629ee8942e3fe0a707bd032e70365d5b8f66a003802e044", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/55597ac4bd01308bd97a75a7d6dfbce0de1757fe717eb78783a843cdc052138cda1bf63b0265d41b79698cb7db225da705b57b306068d2b9c5e41c01bc0fba16"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 23:31:46.184352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:31:46.184680) ========= 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=eca2ab57546de7b41f256b19000001ef-1469599871.73887-108913495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 23:31:46.267029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 23:31:46.267354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 23:31:46.267687) ========= ========= Total master_lag: 0.17 =========