builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1469892536.82 results: success (0) buildid: 20160730071025 builduid: 15e3ced9e176471f852112b5d58e124f revision: a4d524f408817b7fe58258084902f41691548852 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:56.824565) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:56.825178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:56.825581) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:56.863115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:56.863511) ========= 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=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 08:28:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-07-30 08:28:57 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102187 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.001732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.002127) ========= 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=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022742 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.079046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.079381) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a4d524f408817b7fe58258084902f41691548852 --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 a4d524f408817b7fe58258084902f41691548852 --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=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 08:28:57,326 truncating revision to first 12 chars 2016-07-30 08:28:57,326 Setting DEBUG logging. 2016-07-30 08:28:57,326 attempt 1/10 2016-07-30 08:28:57,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a4d524f40881?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 08:28:57,630 unpacking tar archive at: autoland-a4d524f40881/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638344 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.737959) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.738265) ========= script_repo_revision: a4d524f408817b7fe58258084902f41691548852 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.738712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.738979) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 08:28:57.750443) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-30 08:28:57.750730) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:28:58 INFO - MultiFileLogger online at 20160730 08:28:58 in /builds/slave/test 08:28:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 08:28:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:28:58 INFO - {'append_to_log': False, 08:28:58 INFO - 'base_work_dir': '/builds/slave/test', 08:28:58 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:28:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:28:58 INFO - 'branch': 'Autoland-Non-PGO', 08:28:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:28:58 INFO - 'config_files': ('talos/linux_config.py',), 08:28:58 INFO - 'default_actions': ('clobber', 08:28:58 INFO - 'read-buildbot-config', 08:28:58 INFO - 'download-and-extract', 08:28:58 INFO - 'populate-webroot', 08:28:58 INFO - 'create-virtualenv', 08:28:58 INFO - 'install', 08:28:58 INFO - 'run-tests'), 08:28:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:28:58 INFO - 'download_minidump_stackwalk': True, 08:28:58 INFO - 'download_symbols': 'ondemand', 08:28:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:28:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:28:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:28:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:28:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:28:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:28:58 INFO - 'installer_path': 'installer.exe', 08:28:58 INFO - 'log_level': 'info', 08:28:58 INFO - 'log_name': 'talos', 08:28:58 INFO - 'log_to_console': True, 08:28:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:28:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:28:58 INFO - 'opt_config_files': (), 08:28:58 INFO - 'pip_index': False, 08:28:58 INFO - 'sps_profile': False, 08:28:58 INFO - 'sps_profile_interval': 0, 08:28:58 INFO - 'suite': 'dromaeojs', 08:28:58 INFO - 'system_bits': '32', 08:28:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:28:58 INFO - 'title': 'talos-linux64-ix-028', 08:28:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:28:58 INFO - 'use_talos_json': True, 08:28:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:28:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:28:58 INFO - 'work_dir': 'build'} 08:28:58 INFO - [mozharness: 2016-07-30 15:28:58.087181Z] Running clobber step. 08:28:58 INFO - Running pre-action listener: _resource_record_pre_action 08:28:58 INFO - Running main action method: clobber 08:28:58 INFO - rmtree: /builds/slave/test/build 08:28:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:28:59 INFO - Running post-action listener: _resource_record_post_action 08:28:59 INFO - [mozharness: 2016-07-30 15:28:59.067013Z] Finished clobber step (success) 08:28:59 INFO - [mozharness: 2016-07-30 15:28:59.067114Z] Running read-buildbot-config step. 08:28:59 INFO - Running pre-action listener: _resource_record_pre_action 08:28:59 INFO - Running main action method: read_buildbot_config 08:28:59 INFO - Using buildbot properties: 08:28:59 INFO - { 08:28:59 INFO - "project": "", 08:28:59 INFO - "product": "firefox", 08:28:59 INFO - "script_repo_revision": "production", 08:28:59 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:28:59 INFO - "repository": "", 08:28:59 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:28:59 INFO - "buildid": "20160730071025", 08:28:59 INFO - "pgo_build": "False", 08:28:59 INFO - "basedir": "/builds/slave/test", 08:28:59 INFO - "buildnumber": 186, 08:28:59 INFO - "slavename": "talos-linux64-ix-028", 08:28:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:28:59 INFO - "platform": "ubuntu64_hw", 08:28:59 INFO - "scheduler": "tests-autoland-linux64-talos", 08:28:59 INFO - "branch": "autoland", 08:28:59 INFO - "repo_path": "integration/autoland", 08:28:59 INFO - "revision": "a4d524f408817b7fe58258084902f41691548852", 08:28:59 INFO - "stage_platform": "linux64", 08:28:59 INFO - "builduid": "15e3ced9e176471f852112b5d58e124f", 08:28:59 INFO - "slavebuilddir": "test" 08:28:59 INFO - } 08:28:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:28:59 INFO - Running post-action listener: _resource_record_post_action 08:28:59 INFO - [mozharness: 2016-07-30 15:28:59.069574Z] Finished read-buildbot-config step (success) 08:28:59 INFO - [mozharness: 2016-07-30 15:28:59.069963Z] Running download-and-extract step. 08:28:59 INFO - Running pre-action listener: _resource_record_pre_action 08:28:59 INFO - Running main action method: download_and_extract 08:28:59 INFO - mkdir: /builds/slave/test/build/tests 08:28:59 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')]} 08:28:59 INFO - https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:28:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:28:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:28:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:28:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:02 INFO - Downloaded 1361 bytes. 08:29:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:29:02 INFO - Using the following test package requirements: 08:29:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:29:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:29:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'jsshell-linux-x86_64.zip'], 08:29:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:29:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:29:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:29:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:29:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:29:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:29: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')]} 08:29:02 INFO - https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:29:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:29:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:29:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:03 INFO - Downloaded 22760926 bytes. 08:29:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:29:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:29:04 INFO - Return code: 0 08:29:04 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 08:29:04 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')]} 08:29:04 INFO - https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:29:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:29:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:29:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:08 INFO - Downloaded 22760926 bytes. 08:29:08 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'] 08:29:08 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 08:29:10 INFO - Return code: 0 08:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:10 INFO - https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:29:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:29:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:29:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:11 INFO - Downloaded 14413725 bytes. 08:29:11 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'] 08:29:11 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 08:29:11 INFO - Return code: 0 08:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:11 INFO - https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:29:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:29:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:29:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PmMJbhK2RlSy-4InxfOFBw/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 08:29:13 INFO - Downloaded 57168222 bytes. 08:29:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:29:13 INFO - mkdir: /builds/slave/test/properties 08:29:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:29:13 INFO - Writing to file /builds/slave/test/properties/build_url 08:29:13 INFO - Contents: 08:29:13 INFO - build_url:https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:29:13 INFO - Running post-action listener: _resource_record_post_action 08:29:13 INFO - Running post-action listener: set_extra_try_arguments 08:29:13 INFO - [mozharness: 2016-07-30 15:29:13.714162Z] Finished download-and-extract step (success) 08:29:13 INFO - [mozharness: 2016-07-30 15:29:13.714491Z] Running populate-webroot step. 08:29:13 INFO - Running pre-action listener: _resource_record_pre_action 08:29:13 INFO - Running main action method: populate_webroot 08:29:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:29:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:29:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:29:13 INFO - '--tppagecycles', 08:29:13 INFO - '7'], 08:29:13 INFO - 'tests': ['tsvgm']}}, 08:29:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:29:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:29:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:29:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:29:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['damp', 'tps']}, 08:29:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'tests': ['damp', 'tps']}, 08:29:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['dromaeo_dom']}, 08:29:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:29:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['basic_compositor_video']}, 08:29:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:29:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['a11yr', 08:29:13 INFO - 'ts_paint', 08:29:13 INFO - 'tpaint', 08:29:13 INFO - 'sessionrestore', 08:29:13 INFO - 'sessionrestore_no_auto_restore', 08:29:13 INFO - 'tabpaint']}, 08:29:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['tsvgx', 08:29:13 INFO - 'tsvgr_opacity', 08:29:13 INFO - 'tart', 08:29:13 INFO - 'tscrollx', 08:29:13 INFO - 'cart']}, 08:29:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:29:13 INFO - 'tsvgr_opacity', 08:29:13 INFO - 'tart', 08:29:13 INFO - 'tscrollx', 08:29:13 INFO - 'cart']}, 08:29:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'talos_options': ['--disable-e10s'], 08:29:13 INFO - 'tests': ['tp5o']}, 08:29:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'tests': ['tp5o']}, 08:29:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'talos_options': ['--disable-e10s', 08:29:13 INFO - '--xperf_path', 08:29:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:13 INFO - 'tests': ['tp5n']}, 08:29:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:29:13 INFO - 'talos_options': ['--xperf_path', 08:29:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:13 INFO - 'tests': ['tp5n']}}, 08:29:13 INFO - 'talos.zip': {'path': '', 08:29:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:29:13 INFO - Running post-action listener: _resource_record_post_action 08:29:13 INFO - [mozharness: 2016-07-30 15:29:13.733573Z] Finished populate-webroot step (success) 08:29:13 INFO - [mozharness: 2016-07-30 15:29:13.733682Z] Running create-virtualenv step. 08:29:13 INFO - Running pre-action listener: _resource_record_pre_action 08:29:13 INFO - Running main action method: create_virtualenv 08:29:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:29:13 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 08:29:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:29:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:29:13 INFO - Using real prefix '/usr' 08:29:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:29:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:29:16 INFO - Installing pip.................done. 08:29:16 INFO - Return code: 0 08:29:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:29:16 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')]} 08:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:16 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')]} 08:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:16 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:16 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 08:29:16 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 08:29:16 INFO - Using env: {'DISPLAY': ':0', 08:29:16 INFO - 'HOME': '/home/cltbld', 08:29:16 INFO - 'LANG': 'en_US.UTF-8', 08:29:16 INFO - 'LANGUAGE': 'en_US:en', 08:29:16 INFO - 'LOGNAME': 'cltbld', 08:29:16 INFO - 'MAIL': '/var/mail/cltbld', 08:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:16 INFO - 'MOZ_NO_REMOTE': '1', 08:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:16 INFO - 'NO_EM_RESTART': '1', 08:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:16 INFO - 'PWD': '/builds/slave/test', 08:29:16 INFO - 'SHELL': '/bin/bash', 08:29:16 INFO - 'SHLVL': '1', 08:29:16 INFO - 'TERM': 'linux', 08:29:16 INFO - 'TMOUT': '86400', 08:29:16 INFO - 'USER': 'cltbld', 08:29:16 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:16 INFO - '_': '/tools/buildbot/bin/python'} 08:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:16 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 08:29:16 INFO - Cleaning up... 08:29:16 INFO - Return code: 0 08:29:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:29:16 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')]} 08:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:16 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')]} 08:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:16 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:16 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 08:29:16 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 08:29:16 INFO - Using env: {'DISPLAY': ':0', 08:29:16 INFO - 'HOME': '/home/cltbld', 08:29:16 INFO - 'LANG': 'en_US.UTF-8', 08:29:16 INFO - 'LANGUAGE': 'en_US:en', 08:29:16 INFO - 'LOGNAME': 'cltbld', 08:29:16 INFO - 'MAIL': '/var/mail/cltbld', 08:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:16 INFO - 'MOZ_NO_REMOTE': '1', 08:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:16 INFO - 'NO_EM_RESTART': '1', 08:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:16 INFO - 'PWD': '/builds/slave/test', 08:29:16 INFO - 'SHELL': '/bin/bash', 08:29:16 INFO - 'SHLVL': '1', 08:29:16 INFO - 'TERM': 'linux', 08:29:16 INFO - 'TMOUT': '86400', 08:29:16 INFO - 'USER': 'cltbld', 08:29:16 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:16 INFO - '_': '/tools/buildbot/bin/python'} 08:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:16 INFO - Downloading/unpacking psutil>=3.1.1 08:29:16 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 08:29:16 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 08:29:16 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 08:29:16 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 08:29:16 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 08:29:16 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 08:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:29:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:18 INFO - Installing collected packages: psutil 08:29:18 INFO - Running setup.py install for psutil 08:29:18 INFO - building 'psutil._psutil_linux' extension 08:29:18 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 08:29:18 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 08:29:19 INFO - building 'psutil._psutil_posix' extension 08:29:19 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 08:29:19 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 08:29:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:19 INFO - Successfully installed psutil 08:29:19 INFO - Cleaning up... 08:29:19 INFO - Return code: 0 08:29:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:29:19 INFO - Using env: {'DISPLAY': ':0', 08:29:19 INFO - 'HOME': '/home/cltbld', 08:29:19 INFO - 'LANG': 'en_US.UTF-8', 08:29:19 INFO - 'LANGUAGE': 'en_US:en', 08:29:19 INFO - 'LOGNAME': 'cltbld', 08:29:19 INFO - 'MAIL': '/var/mail/cltbld', 08:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:19 INFO - 'MOZ_NO_REMOTE': '1', 08:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:19 INFO - 'NO_EM_RESTART': '1', 08:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:19 INFO - 'PWD': '/builds/slave/test', 08:29:19 INFO - 'SHELL': '/bin/bash', 08:29:19 INFO - 'SHLVL': '1', 08:29:19 INFO - 'TERM': 'linux', 08:29:19 INFO - 'TMOUT': '86400', 08:29:19 INFO - 'USER': 'cltbld', 08:29:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:19 INFO - '_': '/tools/buildbot/bin/python'} 08:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:29:21 INFO - Installing collected packages: mozsystemmonitor 08:29:21 INFO - Running setup.py install for mozsystemmonitor 08:29:21 INFO - Successfully installed mozsystemmonitor 08:29:21 INFO - Cleaning up... 08:29:21 INFO - Return code: 0 08:29:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:29:21 INFO - Using env: {'DISPLAY': ':0', 08:29:21 INFO - 'HOME': '/home/cltbld', 08:29:21 INFO - 'LANG': 'en_US.UTF-8', 08:29:21 INFO - 'LANGUAGE': 'en_US:en', 08:29:21 INFO - 'LOGNAME': 'cltbld', 08:29:21 INFO - 'MAIL': '/var/mail/cltbld', 08:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:21 INFO - 'MOZ_NO_REMOTE': '1', 08:29:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:21 INFO - 'NO_EM_RESTART': '1', 08:29:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:21 INFO - 'PWD': '/builds/slave/test', 08:29:21 INFO - 'SHELL': '/bin/bash', 08:29:21 INFO - 'SHLVL': '1', 08:29:21 INFO - 'TERM': 'linux', 08:29:21 INFO - 'TMOUT': '86400', 08:29:21 INFO - 'USER': 'cltbld', 08:29:21 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:21 INFO - '_': '/tools/buildbot/bin/python'} 08:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:29:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:29:23 INFO - warning: no files found matching '*.txt' 08:29:23 INFO - no previously-included directories found matching 'build' 08:29:23 INFO - no previously-included directories found matching 'dist' 08:29:23 INFO - no previously-included directories found matching '.git*' 08:29:23 INFO - Installing collected packages: jsonschema, functools32 08:29:23 INFO - Running setup.py install for functools32 08:29:24 INFO - warning: no files found matching '*.txt' 08:29:24 INFO - no previously-included directories found matching 'build' 08:29:24 INFO - no previously-included directories found matching 'dist' 08:29:24 INFO - no previously-included directories found matching '.git*' 08:29:24 INFO - Successfully installed jsonschema functools32 08:29:24 INFO - Cleaning up... 08:29:24 INFO - Return code: 0 08:29:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:29:24 INFO - Using env: {'DISPLAY': ':0', 08:29:24 INFO - 'HOME': '/home/cltbld', 08:29:24 INFO - 'LANG': 'en_US.UTF-8', 08:29:24 INFO - 'LANGUAGE': 'en_US:en', 08:29:24 INFO - 'LOGNAME': 'cltbld', 08:29:24 INFO - 'MAIL': '/var/mail/cltbld', 08:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:24 INFO - 'MOZ_NO_REMOTE': '1', 08:29:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:24 INFO - 'NO_EM_RESTART': '1', 08:29:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:24 INFO - 'PWD': '/builds/slave/test', 08:29:24 INFO - 'SHELL': '/bin/bash', 08:29:24 INFO - 'SHLVL': '1', 08:29:24 INFO - 'TERM': 'linux', 08:29:24 INFO - 'TMOUT': '86400', 08:29:24 INFO - 'USER': 'cltbld', 08:29:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:24 INFO - '_': '/tools/buildbot/bin/python'} 08:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:29:24 INFO - Cleaning up... 08:29:24 INFO - Return code: 0 08:29:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:29:24 INFO - Using env: {'DISPLAY': ':0', 08:29:24 INFO - 'HOME': '/home/cltbld', 08:29:24 INFO - 'LANG': 'en_US.UTF-8', 08:29:24 INFO - 'LANGUAGE': 'en_US:en', 08:29:24 INFO - 'LOGNAME': 'cltbld', 08:29:24 INFO - 'MAIL': '/var/mail/cltbld', 08:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:24 INFO - 'MOZ_NO_REMOTE': '1', 08:29:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:24 INFO - 'NO_EM_RESTART': '1', 08:29:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:24 INFO - 'PWD': '/builds/slave/test', 08:29:24 INFO - 'SHELL': '/bin/bash', 08:29:24 INFO - 'SHLVL': '1', 08:29:24 INFO - 'TERM': 'linux', 08:29:24 INFO - 'TMOUT': '86400', 08:29:24 INFO - 'USER': 'cltbld', 08:29:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:24 INFO - '_': '/tools/buildbot/bin/python'} 08:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:24 INFO - Downloading/unpacking blobuploader==1.2.4 08:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:27 INFO - Downloading blobuploader-1.2.4.tar.gz 08:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:29:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:29:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:28 INFO - Downloading docopt-0.6.1.tar.gz 08:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:29:28 INFO - Installing collected packages: blobuploader, requests, docopt 08:29:28 INFO - Running setup.py install for blobuploader 08:29:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:29:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:29:28 INFO - Running setup.py install for requests 08:29:28 INFO - Running setup.py install for docopt 08:29:29 INFO - Successfully installed blobuploader requests docopt 08:29:29 INFO - Cleaning up... 08:29:29 INFO - Return code: 0 08:29:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:29 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:29 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 08:29:29 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 08:29:29 INFO - Using env: {'DISPLAY': ':0', 08:29:29 INFO - 'HOME': '/home/cltbld', 08:29:29 INFO - 'LANG': 'en_US.UTF-8', 08:29:29 INFO - 'LANGUAGE': 'en_US:en', 08:29:29 INFO - 'LOGNAME': 'cltbld', 08:29:29 INFO - 'MAIL': '/var/mail/cltbld', 08:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:29 INFO - 'MOZ_NO_REMOTE': '1', 08:29:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:29 INFO - 'NO_EM_RESTART': '1', 08:29:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:29 INFO - 'PWD': '/builds/slave/test', 08:29:29 INFO - 'SHELL': '/bin/bash', 08:29:29 INFO - 'SHLVL': '1', 08:29:29 INFO - 'TERM': 'linux', 08:29:29 INFO - 'TMOUT': '86400', 08:29:29 INFO - 'USER': 'cltbld', 08:29:29 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:29 INFO - '_': '/tools/buildbot/bin/python'} 08:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:29 INFO - Running setup.py (path:/tmp/pip-2d63YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:29 INFO - Running setup.py (path:/tmp/pip-ZAitoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:29 INFO - Running setup.py (path:/tmp/pip-1MLBMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:29 INFO - Running setup.py (path:/tmp/pip-VxnAhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:29 INFO - Running setup.py (path:/tmp/pip-YJP8JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:29 INFO - Running setup.py (path:/tmp/pip-MWPgaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:29 INFO - Running setup.py (path:/tmp/pip-by1Jva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:29 INFO - Running setup.py (path:/tmp/pip-vCtDB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:29 INFO - Running setup.py (path:/tmp/pip-9WJWyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:29 INFO - Running setup.py (path:/tmp/pip-Pbji1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:30 INFO - Running setup.py (path:/tmp/pip-_Y3yoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:30 INFO - Running setup.py (path:/tmp/pip-tbdP_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:30 INFO - Running setup.py (path:/tmp/pip-rb7GgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:30 INFO - Running setup.py (path:/tmp/pip-sKVVVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:30 INFO - Running setup.py (path:/tmp/pip-UCM_yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:30 INFO - Running setup.py (path:/tmp/pip-LptSSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:30 INFO - Running setup.py (path:/tmp/pip-3SggNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:29:30 INFO - Running setup.py install for manifestparser 08:29:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:29:30 INFO - Running setup.py install for mozcrash 08:29:30 INFO - Running setup.py install for mozdebug 08:29:30 INFO - Running setup.py install for mozdevice 08:29:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:29:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:29:30 INFO - Running setup.py install for mozfile 08:29:31 INFO - Running setup.py install for mozhttpd 08:29:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozinfo 08:29:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozInstall 08:29:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozleak 08:29:31 INFO - Running setup.py install for mozlog 08:29:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for moznetwork 08:29:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozprocess 08:29:31 INFO - Running setup.py install for mozprofile 08:29:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozrunner 08:29:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:29:31 INFO - Running setup.py install for mozscreenshot 08:29:32 INFO - Running setup.py install for moztest 08:29:32 INFO - Running setup.py install for mozversion 08:29:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:29:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:29:32 INFO - Cleaning up... 08:29:32 INFO - Return code: 0 08:29:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:32 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:32 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 08:29:32 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 08:29:32 INFO - Using env: {'DISPLAY': ':0', 08:29:32 INFO - 'HOME': '/home/cltbld', 08:29:32 INFO - 'LANG': 'en_US.UTF-8', 08:29:32 INFO - 'LANGUAGE': 'en_US:en', 08:29:32 INFO - 'LOGNAME': 'cltbld', 08:29:32 INFO - 'MAIL': '/var/mail/cltbld', 08:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:32 INFO - 'MOZ_NO_REMOTE': '1', 08:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:32 INFO - 'NO_EM_RESTART': '1', 08:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:32 INFO - 'PWD': '/builds/slave/test', 08:29:32 INFO - 'SHELL': '/bin/bash', 08:29:32 INFO - 'SHLVL': '1', 08:29:32 INFO - 'TERM': 'linux', 08:29:32 INFO - 'TMOUT': '86400', 08:29:32 INFO - 'USER': 'cltbld', 08:29:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:32 INFO - '_': '/tools/buildbot/bin/python'} 08:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:32 INFO - Running setup.py (path:/tmp/pip-NoaZF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:32 INFO - Running setup.py (path:/tmp/pip-1Dq4PK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:32 INFO - Running setup.py (path:/tmp/pip-MHtuqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:32 INFO - Running setup.py (path:/tmp/pip-w37bs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:32 INFO - Running setup.py (path:/tmp/pip-SUv7gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:32 INFO - Running setup.py (path:/tmp/pip-UEdVrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:32 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)) 08:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:32 INFO - Running setup.py (path:/tmp/pip-TZ5x0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:33 INFO - Running setup.py (path:/tmp/pip-PDX6gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:33 INFO - Running setup.py (path:/tmp/pip-CrFtA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:33 INFO - Running setup.py (path:/tmp/pip-Vbixy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:33 INFO - Running setup.py (path:/tmp/pip-17XQQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:33 INFO - Running setup.py (path:/tmp/pip-WCUnDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:33 INFO - Running setup.py (path:/tmp/pip-3vKN58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:33 INFO - Running setup.py (path:/tmp/pip-vjxPIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:33 INFO - Running setup.py (path:/tmp/pip-xAmaDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:33 INFO - Running setup.py (path:/tmp/pip-YRnuSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:33 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)) 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:33 INFO - Running setup.py (path:/tmp/pip-cv_rLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:33 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)) 08:29:33 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)) 08:29:33 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)) 08:29:33 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)) 08:29:33 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)) 08:29:33 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)) 08:29:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:35 INFO - Downloading blessings-1.6.tar.gz 08:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:29:35 INFO - Installing collected packages: blessings 08:29:35 INFO - Running setup.py install for blessings 08:29:35 INFO - Successfully installed blessings 08:29:35 INFO - Cleaning up... 08:29:35 INFO - Return code: 0 08:29:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:29:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:36 INFO - Reading from file tmpfile_stdout 08:29:36 INFO - Current package versions: 08:29:36 INFO - argparse == 1.2.1 08:29:36 INFO - blessings == 1.6 08:29:36 INFO - blobuploader == 1.2.4 08:29:36 INFO - docopt == 0.6.1 08:29:36 INFO - functools32 == 3.2.3-2 08:29:36 INFO - jsonschema == 2.5.1 08:29:36 INFO - manifestparser == 1.1 08:29:36 INFO - mozInstall == 1.12 08:29:36 INFO - mozcrash == 0.17 08:29:36 INFO - mozdebug == 0.1 08:29:36 INFO - mozdevice == 0.48 08:29:36 INFO - mozfile == 1.2 08:29:36 INFO - mozhttpd == 0.7 08:29:36 INFO - mozinfo == 0.9 08:29:36 INFO - mozleak == 0.1 08:29:36 INFO - mozlog == 3.3 08:29:36 INFO - moznetwork == 0.27 08:29:36 INFO - mozprocess == 0.23 08:29:36 INFO - mozprofile == 0.28 08:29:36 INFO - mozrunner == 6.12 08:29:36 INFO - mozscreenshot == 0.1 08:29:36 INFO - mozsystemmonitor == 0.3 08:29:36 INFO - moztest == 0.7 08:29:36 INFO - mozversion == 1.4 08:29:36 INFO - psutil == 3.1.1 08:29:36 INFO - requests == 1.2.3 08:29:36 INFO - wsgiref == 0.1.2 08:29:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:36 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')]} 08:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:36 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')]} 08:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:36 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:36 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 08:29:36 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 08:29:36 INFO - Using env: {'DISPLAY': ':0', 08:29:36 INFO - 'HOME': '/home/cltbld', 08:29:36 INFO - 'LANG': 'en_US.UTF-8', 08:29:36 INFO - 'LANGUAGE': 'en_US:en', 08:29:36 INFO - 'LOGNAME': 'cltbld', 08:29:36 INFO - 'MAIL': '/var/mail/cltbld', 08:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:36 INFO - 'MOZ_NO_REMOTE': '1', 08:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:36 INFO - 'NO_EM_RESTART': '1', 08:29:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:36 INFO - 'PWD': '/builds/slave/test', 08:29:36 INFO - 'SHELL': '/bin/bash', 08:29:36 INFO - 'SHLVL': '1', 08:29:36 INFO - 'TERM': 'linux', 08:29:36 INFO - 'TMOUT': '86400', 08:29:36 INFO - 'USER': 'cltbld', 08:29:36 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:36 INFO - '_': '/tools/buildbot/bin/python'} 08:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 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)) 08:29:36 INFO - Cleaning up... 08:29:36 INFO - Return code: 0 08:29:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:29:36 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')]} 08:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:36 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')]} 08:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:36 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 0x2561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, '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': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', '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 08:29:36 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 08:29:36 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 08:29:36 INFO - Using env: {'DISPLAY': ':0', 08:29:36 INFO - 'HOME': '/home/cltbld', 08:29:36 INFO - 'LANG': 'en_US.UTF-8', 08:29:36 INFO - 'LANGUAGE': 'en_US:en', 08:29:36 INFO - 'LOGNAME': 'cltbld', 08:29:36 INFO - 'MAIL': '/var/mail/cltbld', 08:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:36 INFO - 'MOZ_NO_REMOTE': '1', 08:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:36 INFO - 'NO_EM_RESTART': '1', 08:29:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:36 INFO - 'PWD': '/builds/slave/test', 08:29:36 INFO - 'SHELL': '/bin/bash', 08:29:36 INFO - 'SHLVL': '1', 08:29:36 INFO - 'TERM': 'linux', 08:29:36 INFO - 'TMOUT': '86400', 08:29:36 INFO - 'USER': 'cltbld', 08:29:36 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:36 INFO - '_': '/tools/buildbot/bin/python'} 08:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:29:36 INFO - Cleaning up... 08:29:36 INFO - Return code: 0 08:29:36 INFO - Running post-action listener: _resource_record_post_action 08:29:36 INFO - Running post-action listener: _start_resource_monitoring 08:29:36 INFO - Starting resource monitoring. 08:29:36 INFO - [mozharness: 2016-07-30 15:29:36.577395Z] Finished create-virtualenv step (success) 08:29:36 INFO - [mozharness: 2016-07-30 15:29:36.577890Z] Running install step. 08:29:36 INFO - Running pre-action listener: _resource_record_pre_action 08:29:36 INFO - Running main action method: install 08:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:36 INFO - Reading from file tmpfile_stdout 08:29:36 INFO - Detecting whether we're running mozinstall >=1.0... 08:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:29:36 INFO - Reading from file tmpfile_stdout 08:29:36 INFO - Output received: 08:29:36 INFO - Usage: mozinstall [options] installer 08:29:36 INFO - Options: 08:29:36 INFO - -h, --help show this help message and exit 08:29:36 INFO - -d DEST, --destination=DEST 08:29:36 INFO - Directory to install application into. [default: 08:29:36 INFO - "/builds/slave/test"] 08:29:36 INFO - --app=APP Application being installed. [default: firefox] 08:29:36 INFO - mkdir: /builds/slave/test/build/application 08:29:36 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'] 08:29:36 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 08:29:53 INFO - Reading from file tmpfile_stdout 08:29:53 INFO - Output received: 08:29:53 INFO - /builds/slave/test/build/application/firefox/firefox 08:29:53 INFO - Running post-action listener: _resource_record_post_action 08:29:53 INFO - [mozharness: 2016-07-30 15:29:53.373212Z] Finished install step (success) 08:29:53 INFO - [mozharness: 2016-07-30 15:29:53.373492Z] Running run-tests step. 08:29:53 INFO - Running pre-action listener: _resource_record_pre_action 08:29:53 INFO - Running main action method: run_tests 08:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:29:53 INFO - Python 2.7.3 08:29:53 INFO - Return code: 0 08:29:53 INFO - grabbing minidump binary from tooltool 08:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:29:53 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 0x28203b0>, '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 0x28212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2821740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:29:53 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 08:29:53 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 08:29:53 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 08:29:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:29:53 INFO - Return code: 0 08:29:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:29:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:29:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:29:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:29:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 08:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 08:29:53 INFO - Using env: {'DISPLAY': ':0', 08:29:53 INFO - 'HOME': '/home/cltbld', 08:29:53 INFO - 'LANG': 'en_US.UTF-8', 08:29:53 INFO - 'LANGUAGE': 'en_US:en', 08:29:53 INFO - 'LOGNAME': 'cltbld', 08:29:53 INFO - 'MAIL': '/var/mail/cltbld', 08:29:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:29:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:53 INFO - 'MOZ_NO_REMOTE': '1', 08:29:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:53 INFO - 'NO_EM_RESTART': '1', 08:29:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:53 INFO - 'PWD': '/builds/slave/test', 08:29:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:29:53 INFO - 'SHELL': '/bin/bash', 08:29:53 INFO - 'SHLVL': '1', 08:29:53 INFO - 'TERM': 'linux', 08:29:53 INFO - 'TMOUT': '86400', 08:29:53 INFO - 'USER': 'cltbld', 08:29:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157', 08:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:53 INFO - '_': '/tools/buildbot/bin/python'} 08:29:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 08:29:53 INFO - mozversion application_buildid: 20160730071025 08:29:53 INFO - mozversion application_changeset: a4d524f408817b7fe58258084902f41691548852 08:29:53 INFO - mozversion application_display_name: Nightly 08:29:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:29:53 INFO - mozversion application_name: Firefox 08:29:53 INFO - mozversion application_remotingname: firefox 08:29:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:29:53 INFO - mozversion application_vendor: Mozilla 08:29:53 INFO - mozversion application_version: 50.0a1 08:29:53 INFO - mozversion platform_buildid: 20160730071025 08:29:53 INFO - mozversion platform_changeset: a4d524f408817b7fe58258084902f41691548852 08:29:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:29:53 INFO - mozversion platform_version: 50.0a1 08:29:53 INFO - using testdate: 1469892593 08:29:53 INFO - actual date: 1469892593 08:29:53 INFO - starting webserver on 'localhost:49705' 08:29:53 INFO - SUITE-START | Running 2 tests 08:29:53 INFO - TEST-START | dromaeo_css 08:29:53 INFO - operating with platform_type : linux_ 08:29:53 INFO - Initialising browser for dromaeo_css test... 08:29:53 INFO - TEST-INFO | started process 23395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS64BaQ/profile http://localhost:49705/getInfo.html) 08:29:57 INFO - PROCESS | 23395 | JavaScript error: , line 0: uncaught exception: undefined 08:29:58 INFO - PROCESS | 23395 | __metrics Screen width/height:1600/1200 08:29:58 INFO - PROCESS | 23395 | colorDepth:24 08:29:58 INFO - PROCESS | 23395 | Browser inner width/height: 1024/697 08:29:58 INFO - PROCESS | 23395 | __metrics 08:29:59 INFO - PROCESS | 23395 | 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" 08:29:59 INFO - PROCESS | 23395 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:29:59 INFO - PROCESS | 23395 | console.error: PushService: 08:29:59 INFO - PROCESS | 23395 | stateChangeProcessEnqueue: Error transitioning state 08:29:59 INFO - PROCESS | 23395 | UnknownError 08:30:00 INFO - TEST-INFO | 23395: exit 0 08:30:00 INFO - Browser initialized. 08:30:00 INFO - Running cycle 1/1 for dromaeo_css test... 08:30:00 INFO - TEST-INFO | started process 23474 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS64BaQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:12 INFO - PROCESS | 23474 | RSS: Main: 159477760 08:30:12 INFO - PROCESS | 23474 | 08:31:04 INFO - PROCESS | 23474 | 1469892664383 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] 08:33:16 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49705/tests/dromaeo/cssquery-ext.html) 08:33:17 INFO - PROCESS | 23474 | RSS: Main: 281788416 08:33:17 INFO - PROCESS | 23474 | 08:36:20 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-ext.html (next: http://localhost:49705/tests/dromaeo/cssquery-jquery.html) 08:36:20 INFO - PROCESS | 23474 | RSS: Main: 243216384 08:36:20 INFO - PROCESS | 23474 | 08:39:22 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49705/tests/dromaeo/cssquery-mootools.html) 08:39:23 INFO - PROCESS | 23474 | RSS: Main: 225931264 08:39:23 INFO - PROCESS | 23474 | 08:42:25 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49705/tests/dromaeo/cssquery-prototype.html) 08:42:26 INFO - PROCESS | 23474 | RSS: Main: 195682304 08:42:26 INFO - PROCESS | 23474 | 08:45:29 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49705/tests/dromaeo/cssquery-yui.html) 08:45:29 INFO - PROCESS | 23474 | RSS: Main: 188739584 08:45:29 INFO - PROCESS | 23474 | 08:48:32 INFO - PROCESS | 23474 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-yui.html (next: http://localhost:49705/tests/dromaeo/cssquery-dojo.html) 08:48:33 INFO - PROCESS | 23474 | RSS: Main: 277110784 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | __start_tp_report 08:48:33 INFO - PROCESS | 23474 | _x_x_mozilla_page_load 08:48:33 INFO - PROCESS | 23474 | _x_x_mozilla_page_load_details 08:48:33 INFO - PROCESS | 23474 | |i|pagename|runs| 08:48:33 INFO - PROCESS | 23474 | |0;dojo.html;2666.5466870631994;2672.171626795692;2676.4513274778674;2684.087410280504;2715.0497325935926;4654.3716598259225;4733.8343157989475;4889.364382630258;4958.7768550415185;4961.702297862126;4817.807287708496;4869.196582563882;4881.292212629167;4896.485868983759;4944.901101977958;4416.73499590024;4461.5092339842595;4515.119551687422;4529.426371989074;4591.196540605398;4511.255642818947;4524.705894116888;4557.886052848688;4574.634029277667;4596.678232523725;4314.762688052156;4344.804483798237;4347.260965635849;4412.029353542215;4476.597106260419;4362.47650281965;4429.977850110761;4445.533219012022;4450.1767173073085;4458.77706114693;4391.3193455014525;4453.354263631755;4469.7318160910445;4478.57453541913;4523.321501774727;4749.287606858964;4876.341693871317;4926.5812405945535;4955.033768415161;4962.0324036812835;5160.200168973815;5212.582993360522;5227.398849132361;5243.213517972296;5316.229146773696;5278.577713782888;5286;5315.867103322448;5315.973420132913;5348.812791552277;4716.787744551435;4781.330613714083;4928.457869634337;4944.0111977604265;4967.751612419364;5258.763355649005;5309.65487243328;5315.04329220744;5388.2725832012175;5409.83770486886;5099.796008159632;5120.846374608767;5159.509846564571;5167.974160129251;5170.71561064145;5056.317397151411;5245.213218017251;5267.367915850123;5273.736313184326;5275.788968441219;5020.322256495399;5068.138416469209;5109.4379618241965;5111.233315002779;5177.948220517822;5087.389513258433;5094.108531007059;5105.402008177812;5109.20807274873;5111.3355263815465;4356.934645980313;4370.453693288339;4375.453068366454;4403.097365040159;4426.822927082881;3751.1934933989323;3773.8867833965023;3792.3742582474133;3816.675582575512;3818.8663396781535;4498.527654596286;4503.234450143484;4535.4103966484145;4539.72761634303;4546.227141385973;5175.223716442564;5176.171812509981;5212.592599998055;5220.112580861262;5274.7890084396195;4368.301071828492;4437.467503899488;4501.234790085693;4503.774811259424;4552.84065057728;4647.930281045787;4732.1009008289;4737.549932756383;4738;4739.834105806349;4843.806247750051;4850.733209673502;4858.46556878743;4860.927086093782;4911.435184953776;5264.657797243167;5286;5287.312649355559;5295.152775556046;5297.602679799107;4681.672282940298;4692.906141877075;4818.68678535894;4862.82980095695;4958.454569997225;5165.457626949116;5165.535101840853;5166.4316925138955;5207.453217412269;5218.634695571425;5237.921431178612;5252.711100889335;5254.343207099113;5262.657927234717;5265.262863199078;4963.305137280711;5061.342025536656;5090.847274581768;5105.05556472039;5114.74426278692;5128.435872054146;5134.512221338967;5145.305383773292;5172.456892026283;5191.662541934913;5044.520770526794;5050.173869846001;5091.083604950996;5103.081445339874;5103.642745007814;4940;5068.898622027613;5122.231665250093;5131.4868513148385;5183.663061900965;5038.445770965118;5042.168042273131;5042.823501177441;5075.3402057732255;5087.287779710918 08:48:33 INFO - PROCESS | 23474 | |1;ext.html;556.72054484384;564.8644325361912;581.4824805922729;587.4272584230374;597.1878245585999;26920.730792692066;40137.3979390309;41525.16949661005;41556.376654350206;41655.375169372484;41928.580714192845;41977;42245;42266.7886660567;42270.36594451086;7441.404687624977;7775.8056048598955;7933.603319834014;7947.08608510023;9614.42313461191;9800.166985806214;10196.74508137295;10196.898031019706;10197.643082492114;10207.234457415687;10228.43743594102;10278.177745780356;10287.485625718684;10293.073623373895;10297.845532317022;10168.4915754212;10233.692989210336;10233.846492302622;10243.539040743184;10243.84634230487;9950.452725100116;10007.59969601215;10021.348612340174;10021.949890250502;10026.899731002743;39745.60254397448;39996.20007599861;40108.799456002824;40132.19735605301;40164.59835401638;3070.385922815446;3076.9076927692204;3130.1392117167734;3151.149189718786;3153.7950033247766;29027.564586531218;29488.70511294881;29492;29501.409971800444;31055.844720776477;3327.284633803744;3379.459286514146;3381.594208694972;3388.3562123196516;3451.9309613807836;265968;266060;266262;266692;267214;32383.352332953207;54215.72892135593;54568.45431545713;54584.72707636516;54602.45397546053;32980.50529242063;34290.82854585711;34346;34526.136846578534;34549.827250863586;13400.195988240737;13548.661283467993;13556.728865422636;13562.660933476742;13562.728745425036;10094.848577271347;10945.56217751297;10996.450177491093;11228.943855280671;11271.380074096003;7941.245581669732;7968.12350641429;7981.281684648294;8006.079300880357;8064.879026814602;3036.8633411496535;3069.227351468488;3111.5177147542167;3124.265797537577;3140.4347217501067;786.5949036246337;789.2186735132178;832.3050253038781;921.7465197070859;944.0983860413289;27316.45367092647;28687;29095.27261818429;29578;29718.702812971595;887.3965703321803;891.6789955615973;899.7930475990559;912.8539433690578;915.5696822493418;14543.3455494503;15266.236688165547;15280.465183718516;15361.846381536265;15677.137757423452;424913;429888;430181;430219;431154;50662;51182.74408628007;51241.7437912808;51256.74371628118;51288.74355628198;991.9931269761198;1062.8671416072991;1094.496531595475;1144.2791041643877;1144.6337172104847;27678;29248.41503169882;29535.113946581638;29904.551431728105;30120.84939575288;35631;35988.64011359853;36101.638983609824;36195;36203.09492262715;319894;321199;321648;322544;323686;24673.753262467144;25312.873435632704;25493.61759573645;25528.872355638847;26527;42199.7890010548;42482;42930.35604465998;43113;43165.92085197895;36491.63508364883;36711;36953.26093478169;37537.81231093827;37879.62120378871;36165.457518136725;36862.63137368592;36906.63093369139;37003.07492312714;37503.43744843884;35770.28459430745;36301.45547816838;36437.63562364342;36494.45258321074;37171;32063.67936320607;33706.60694288902;33888;34018.31963360769;34247.486287706204;25791;25849.870750646125;25885.482290354463;25917.6112358311;26269.605955910294 08:48:33 INFO - PROCESS | 23474 | |2;jquery.html;351.57108327840024;370.34469783239433;380.63839352615025;384.45599476740404;399.49862922032844;12387.38063096845;12715.618531444059;17107.486775396726;17127.229274682624;17130.914345428257;15887.285072171751;16354.100524471149;17158.571035724082;17451.1274436278;17522.82477175228;3839.520059992501;5161.483851614837;5316.282301889234;5345.251664766935;5351.25082488452;5908.88182236355;6186.412290832363;6256.843578910518;6350.364963503635;6353.30113687494;6112.908306375386;6220.844478888019;6286.182796236483;6327.46216571592;6357.68211589421;6308.905366419507;6347.143135676671;6354.332795056545;6357.968210158966;6359.459445947077;5343.385510666284;5438.945610543883;5468.179773034037;5472.1244600864065;5477.945220547823;20801.5839683207;21262.787372126237;21312.573748525097;21446.463838404008;21478.677819832716;1888.5656299051159;1890.8960007199598;1893.2616279650947;1987.6124155789628;2450.816188785848;13132.934335328262;13645.522406715816;13790.793138102936;13809.378577963915;14210.715785684331;1755.139981409113;1771.7873855137466;1780.6883795335764;1801.8018018018008;1836.6326734653123;70721;72915.63542182255;73211.2678873215;73432.63283683601;73460.26539734642;9193.49435781025;9381.43711377306;9579.664711735057;9726.297897867846;9942.105210531086;12836.358182090858;12898.161619494704;12905.612831615066;12907.548235811702;12942.223466592035;7184.9640751795905;7323;7374.9262507375315;7529.397648188241;7579.317861392501;7357.41140708742;7531.284527969834;7630.809229769189;7666.501677390952;7682.078150621961;5926.911096333559;5964.910526342108;5992.700364981733;6040.788572399945;6141.57009009374;1609.8148712897932;1645.3336398758522;1669.348953907977;1686.1990554486522;1703.3442124782048;787.8069872881181;815.4291995603102;829.5188790501495;839.2488722593245;890.6259371064168;17228.655426891644;17523.12384380776;17773.46679599614;18486.168122434523;18809.435716928514;823.8022874510075;840.8192238668715;856.2336708645727;894.7494701483595;907.9273658107336;10001.449920254456;10521.894781052091;10596.350912271932;10679.092277156373;10726.410047447465;364590;387784;390815;394008;396128;49056.75471622619;69474;71518;72507.27492725005;74461.25538744651;923.1091996223677;941.7316064921602;947.393668052447;972.2853702528636;977.8630991661031;11810.319117208539;16522.339106435607;17070.402535911184;17139.485815425556;17364.305427783253;25601.359966001;28056.71943280623;36639.70242678172;37035.25929481449;37839.43240851334;39523.40714889336;46273;46843.29735053909;46918.76540617275;48285.275720863516;6947.957806329091;7758;7861;7879.881801773093;8309.04445988719;11241.718957025812;11847.407629618658;11968.521259149884;12382.752344953231;12432.191907525981;10431.94784026075;10453.9477302613;10814.945925270324;10847.945760271148;10920.50857711389;10556.524956377136;10841.07850832688;10873.456327183767;11084.889151108708;11104;10476.580936762444;10530.262881598215;10668.46657667098;10884.129269658566;11038.282511636718;7767.378609711097;7942.920570794449;8044.517328960281;8046.235607617267;8117.310028647631;10546.630867919586;10704.571817127227;10747.301425407324;10805.891941080488;11386.032187263843 08:48:33 INFO - PROCESS | 23474 | |3;mootools.html;1376.6558360409897;1434.118017419287;1466.8753155981742;1639.4425895195645;1673.8995660260377;7634.847303053943;8157.38819588531;8166.877496837537;8167.101618821925;8167.224113709188;6825.078614387056;7037.2259051504425;7522.285382888631;7754.108277548084;8005.559694216816;2152.0638522242834;2184.563087382522;2185.530111026129;2187.540616470535;2188.1794327127327;2146.4848436375314;2165.4261620670536;2212.590670725913;2221.611218036845;2222.7221597300336;1801.1354549816097;1866.2628261836567;1868.2060124447055;1875.1093230715437;1877.8685492015563;2068.4725395024248;2120.0883620043373;2125.203048856679;2137.9144834206613;2142.678598210265;2157.6871353653787;2168;2174.4346469917778;2187.84685072045;2190.87950162741;7949.244821741925;8061.355091592659;8070.71752488666;8071.798205044862;8089.2315230053055;4641.791119399635;4788.664793464459;4792.281157826319;4793.976030119862;4797.0645724083815;2889.4510043091746;3013.382256637384;3049.6340439147443;3078.122735020531;3110.2535391506103;953.5232383808096;977.4233202410541;985.4727720669479;995.2533304051416;1002.989970100297;10871.945640271828;10930.125589952784;10931.945340273247;10943.890561094368;10958.287711298819;4128.277551428488;4281.636060934793;4355.956440435619;4426.911461770811;4434.600885920283;9312.394694344846;9328.533573321307;9349.766255843522;9355.90644093564;9368.39105458143;4814.9518504814505;4816.638752093607;4818.325434439182;4819.927701084486;4820.132376172252;4328.523862375137;4365.388845561624;4392.05570802279;4392.736435813861;4393.18726035678;3678.1540245743627;3686.870959516458;3803.6006219347123;3805.8667946621736;3807.6192380762254;1165.143619439711;1168.8889555492215;1171.8359429679851;1176.7528818948117;1181.1141643767176;760.2207737069548;778.116837389567;785.340314136117;787.1144961917843;791.133708589094;5283.418823929364;5306.442823503554;5476.835694929158;5477.7261136943;5593.804216852391;387.11157892636464;389.28371795895123;397.9840806367713;402.6617641181422;408.5914085914086;3909.863154789569;4033.8184781684895;4053.9594604054173;4082.265192265421;4134.214499245134;9872.703818885446;9921.851172232422;9990.600375985025;10017.348872323275;10023.198144148451;9913.702588922344;9955.402675839472;9978.201743860474;9979.103970245775;9993.450360230112;7068.646567671596;7244.565326080452;7398.075240594926;7418.480706350611;7421.962890185515;5432.348118225838;5561.944380556142;5730.71346432685;5775.451332123442;5933.881322373442;8795;8916.687915922912;9052.592633331647;9065.954670226607;9381.953090234505;9198.770030749285;9235.215006724235;9428.811423771349;9520.095590918852;9537.23702103844;6917.446604271546;7097.396721278749;7106.289371062852;7114.6442677866935;7123.465740069619;7629.771106866803;7632.313091821763;7645.732399365996;7684.807879802826;7691.923080769121;6877.415419689382;6896.965515172392;6923.446124310144;7018.368346848604;7127.10911136108;6872.450203983769;6896.448284137357;6906.205786334636;6921.584704917721;6937.722491100501;6450.967745161244;6566.606003639797;6568.146141001639;6571.112899758471;6574.737010519717;4598.770061496979;4648.093621743727;4766.976165119152;4801.567858892717;4847.515248475265;4710.505396933273;4729.385179926588;4800.807967681393;4884.340614017062;4978.751062446936 08:48:33 INFO - PROCESS | 23474 | |4;prototype.html;285.5260267955195;296.842673383107;303.9908802735918;309.4569031349981;312.0560305077141;4724.716517008982;4825.927611085832;4927.117636182828;4981.377327834025;4986.927810520738;4768.451628062774;4893.388326459192;4914.901701965968;4918.376935611249;4921.237208232729;3918.412238164277;3933.8819835404984;3939.172773717523;3956.3669812829953;3964.405339199114;3700.5559332880093;3737.6266071044947;3872.7869967151937;3874.5156855393075;3934.783586902719;3822.273767984088;3824.6557809797114;3837.34765089934;3847.480590120326;3865.3042452358563;3838.2323535293053;3845.0964023454467;3845.192509572993;3853.537575490931;3866.6520013198665;3245.8052516849066;3440.2947395783804;3474.5309383233193;3476.5480487536456;3480.5823301203764;4803.687760295569;4809.292701091097;4809.5430934061205;4812.422509298872;4838.830640927551;4369.497507786582;4387.451568553931;4390.868273951754;4402.8679139625865;4410.62509686677;5007.89826238227;5149.407818100929;5152.896942061176;5153.2270159476;5175.094358487249;3648.9270214595826;3677.613850545681;3679.963200367989;3680.9447858282147;3695.097025032084;5434.701091439969;5443.428440013781;5461.836144915659;5465.939074118467;5478.972605136989;3787.3182827091387;3788.886333410002;3789.2758794748324;3799.8290076946605;3802.2775672622115;3733.439984002421;3786.1670432504807;3794.2221844521805;3824.9426258606145;3831.6743076838225;4110.136871257009;4153;4272.850450234227;4293.184294983943;4342.457192850894;5102.719350435687;5111.3355263815465;5115.335006449212;5117.309163262987;5180.507851754077;1296.4684479363416;1304.8042793580848;1308.9345532723326;1309.0247765414636;1317.413750880868;799.9480033797901;839.261449924063;852.1733918099434;861.8750281209315;865.7143142762873;4056.241755606216;4068.898277543026;4084.3873418986796;4097.569755175723;4098.692598055158;4623.676342656049;4623.734370158598;4637.60580350674;4692;4725.5510726480925;4106.3635136553885;4107.753534787863;4113.650339721157;4123.340265557556;4147.730397524151;2564.6794150731157;2635.5783074707956;2818.3517790908204;2839.1766387747734;2846.4164846206477;5012.147934851084;5015.22264049073;5016.372953380827;5016.473270306638;5028.472010438924;5329.307190065344;5472.5621950243885;5477.972610137003;5501.807436739695;5518.668879867221;4995.950040499694;5007.799688012512;5015.272785446163;5020.246962955513;5037.3955125384455;5316.308879845596;5324.946750532445;5332.0004998125705;5334.493223143795;5338.8932221356135;5013.674111182761;5154.61340399464;5197.792088316425;5198.4801519847715;5203.505666961632;4956.231784073474;4960.404751429852;4998.700077995332;5003.599712023102;5009.223570346602;3669.1194113412057;3700.592934777226;3724.888253352404;3727.6458736419995;3730.421784623388;4816.686915350491;5159.458256882973;5325.680459172462;5372.704501252312;5373.596980226577;5106.595686186329;5111.284420181252;5133.204353325241;5152.381714194321;5158;5132.769025393792;5168.095583272988;5177.145770947752;5178.559822415137;5180.145276029563;4891.755412229445;5064.696118232918;5067.6452648315735;5107.616928730435;5151.6393852431465;5132.923006154836;5189.143791274398;5192.610554208374;5192.610554208525;5203.245529398292;5063.417706963746;5076.340075790124;5093.351457242139;5095.388553373619;5101.183810590288 08:48:33 INFO - PROCESS | 23474 | |5;yui.html;642.2325321241119;645.7158850105955;657.3853447027033;722.6892436252413;749.6102026945987;12755;13468.393922273497;13468.528601498949;13471.66320841977;13494.595162145139;12957.546485872996;13097.869021309807;13120.147190432615;13125.93437032816;13130.803037954438;599.5084031094505;616.22355831652;757.5757575757566;757.8219118507174;775.1008829757483;762.6186906546727;776.2935728487078;779.7972527142927;780.239266714954;780.3756994404482;812.808989887376;814.9429539932207;814.9837003259931;815.0789607743271;815.233680340481;621.5711159300114;781.7732857471326;803.6062329458553;805.697863301262;808.0505406147753;583.5390041866963;585.1047896718028;585.28887401807;585.3268740947901;585.5959388022251;13466.124701894345;13516.93241533786;13536.526221582295;13573.049886507948;13578.796318055238;12427.627171184879;12611.810822837664;12890;13094.279814610192;13334.19994800315;11342.319460832294;11375.715607109805;11681.94159029208;11743.530258789639;12144.149909506337;4051.7771522566245;4058.6447344895487;4115.691323150746;4236.173946080516;4236.406903033578;60301.39698603002;60367;60378.39621603772;60382.698086509285;60570;9824.214062874953;10021.34861234032;10170.237232207613;10320.43237621938;10575.048245657774;18846;18876.716849247274;18884.24463021491;18944.526386840163;18963.146658400405;6713.832154196087;6765.42493888025;6808.148981377328;6841.657917104125;6949.3745562899585;3507.9298414031578;3567.7324200685052;3592.191756854687;3629.491871138043;3673.3837111671314;2753;2823.082498188097;2842.928926776847;2844.1609725131134;2846.8718907649204;496.3324328777788;502.6581924291517;503.32554377134085;505.5702652745137;509.54141272853985;484.32678576778306;499.11656368228034;508.06008883565846;550.9752061157258;554.2462251338177;7817.921820781833;8002.759917202493;8086.312663423557;8087.757367278873;8132.227438393344;533.8585274902154;550.0758725341465;552.2930648769582;565.3893794701712;592.3217915486744;3597.748157628941;3692.8707495237536;3724.348239058208;3828.559715632738;3879.398693202558;86261.56869215614;97391.51304243434;98435;100806.49596751969;100837.49581252194;47843.28235076477;48625.75687121542;85541.57229213814;89425;89646.55176724074;73569;74408.62795685987;75437;76207.61896190484;76499;6807.489438292049;6876.243613202444;7139.892901606584;7289.778430285799;7392.223816499405;45314;45382;46876.531234687216;46897.76551117223;47833.28250076182;60147.39852601418;63269.68365158145;64102.35897640964;64463.35536644761;65884.34115658974;9644.18024467928;9694.418334899929;9699.563519641491;9946.403215807075;10201.132903702972;10788.298760580537;10825.863284355344;10855.565777368816;11114.666560003212;11826.64520064365;9555.140037396668;9594.472304023346;9693.660721874701;10700.357978521313;12801.42393592272;12035.939820301193;12178.817317740064;12535;12919.806202906775;12992.870071299167;10719.410432426292;10754.24720269605;11060.281081729661;11346.602868899548;11440.313581185155;10385.065344119066;10457.424841633783;10989.61536346224;11120.214211493834;11162.944185279022;9846.458444785605;10025.598976040877;10520.947395262974;10875.564977400816;11139.944300278446 08:48:33 INFO - PROCESS | 23474 | __end_tp_report 08:48:33 INFO - PROCESS | 23474 | __start_cc_report 08:48:33 INFO - PROCESS | 23474 | _x_x_mozilla_cycle_collect,1182 08:48:33 INFO - PROCESS | 23474 | __end_cc_report 08:48:33 INFO - PROCESS | 23474 | __startTimestamp1469893713227__endTimestamp 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | ------- Summary: start ------- 08:48:33 INFO - PROCESS | 23474 | Number of tests: 6 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#0] dojo.html Cycles:165 Average:4814.62 Median:4958.78 stddev:522.27 (10.5%) stddev-sans-first:495.94 08:48:33 INFO - PROCESS | 23474 | Values: 2666.5 2672.2 2676.5 2684.1 2715.0 4654.4 4733.8 4889.4 4958.8 4961.7 4817.8 4869.2 4881.3 4896.5 4944.9 4416.7 4461.5 4515.1 4529.4 4591.2 4511.3 4524.7 4557.9 4574.6 4596.7 4314.8 4344.8 4347.3 4412.0 4476.6 4362.5 4430.0 4445.5 4450.2 4458.8 4391.3 4453.4 4469.7 4478.6 4523.3 4749.3 4876.3 4926.6 4955.0 4962.0 5160.2 5212.6 5227.4 5243.2 5316.2 5278.6 5286.0 5315.9 5316.0 5348.8 4716.8 4781.3 4928.5 4944.0 4967.8 5258.8 5309.7 5315.0 5388.3 5409.8 5099.8 5120.8 5159.5 5168.0 5170.7 5056.3 5245.2 5267.4 5273.7 5275.8 5020.3 5068.1 5109.4 5111.2 5177.9 5087.4 5094.1 5105.4 5109.2 5111.3 4356.9 4370.5 4375.5 4403.1 4426.8 3751.2 3773.9 3792.4 3816.7 3818.9 4498.5 4503.2 4535.4 4539.7 4546.2 5175.2 5176.2 5212.6 5220.1 5274.8 4368.3 4437.5 4501.2 4503.8 4552.8 4647.9 4732.1 4737.5 4738.0 4739.8 4843.8 4850.7 4858.5 4860.9 4911.4 5264.7 5286.0 5287.3 5295.2 5297.6 4681.7 4692.9 4818.7 4862.8 4958.5 5165.5 5165.5 5166.4 5207.5 5218.6 5237.9 5252.7 5254.3 5262.7 5265.3 4963.3 5061.3 5090.8 5105.1 5114.7 5128.4 5134.5 5145.3 5172.5 5191.7 5044.5 5050.2 5091.1 5103.1 5103.6 4940.0 5068.9 5122.2 5131.5 5183.7 5038.4 5042.2 5042.8 5075.3 5087.3 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#1] ext.html Cycles:180 Average:48471.45 Median:18638.18 stddev:91362.03 (490.2%) stddev-sans-first:91547.49 08:48:33 INFO - PROCESS | 23474 | Values: 556.7 564.9 581.5 587.4 597.2 26920.7 40137.4 41525.2 41556.4 41655.4 41928.6 41977.0 42245.0 42266.8 42270.4 7441.4 7775.8 7933.6 7947.1 9614.4 9800.2 10196.7 10196.9 10197.6 10207.2 10228.4 10278.2 10287.5 10293.1 10297.8 10168.5 10233.7 10233.8 10243.5 10243.8 9950.5 10007.6 10021.3 10021.9 10026.9 39745.6 39996.2 40108.8 40132.2 40164.6 3070.4 3076.9 3130.1 3151.1 3153.8 29027.6 29488.7 29492.0 29501.4 31055.8 3327.3 3379.5 3381.6 3388.4 3451.9 265968.0 266060.0 266262.0 266692.0 267214.0 32383.4 54215.7 54568.5 54584.7 54602.5 32980.5 34290.8 34346.0 34526.1 34549.8 13400.2 13548.7 13556.7 13562.7 13562.7 10094.8 10945.6 10996.5 11228.9 11271.4 7941.2 7968.1 7981.3 8006.1 8064.9 3036.9 3069.2 3111.5 3124.3 3140.4 786.6 789.2 832.3 921.7 944.1 27316.5 28687.0 29095.3 29578.0 29718.7 887.4 891.7 899.8 912.9 915.6 14543.3 15266.2 15280.5 15361.8 15677.1 424913.0 429888.0 430181.0 430219.0 431154.0 50662.0 51182.7 51241.7 51256.7 51288.7 992.0 1062.9 1094.5 1144.3 1144.6 27678.0 29248.4 29535.1 29904.6 30120.8 35631.0 35988.6 36101.6 36195.0 36203.1 319894.0 321199.0 321648.0 322544.0 323686.0 24673.8 25312.9 25493.6 25528.9 26527.0 42199.8 42482.0 42930.4 43113.0 43165.9 36491.6 36711.0 36953.3 37537.8 37879.6 36165.5 36862.6 36906.6 37003.1 37503.4 35770.3 36301.5 36437.6 36494.5 37171.0 32063.7 33706.6 33888.0 34018.3 34247.5 25791.0 25849.9 25885.5 25917.6 26269.6 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#2] jquery.html Cycles:180 Average:24141.11 Median:194084.23 stddev:63635.01 (32.8%) stddev-sans-first:63788.45 08:48:33 INFO - PROCESS | 23474 | Values: 351.6 370.3 380.6 384.5 399.5 12387.4 12715.6 17107.5 17127.2 17130.9 15887.3 16354.1 17158.6 17451.1 17522.8 3839.5 5161.5 5316.3 5345.3 5351.3 5908.9 6186.4 6256.8 6350.4 6353.3 6112.9 6220.8 6286.2 6327.5 6357.7 6308.9 6347.1 6354.3 6358.0 6359.5 5343.4 5438.9 5468.2 5472.1 5477.9 20801.6 21262.8 21312.6 21446.5 21478.7 1888.6 1890.9 1893.3 1987.6 2450.8 13132.9 13645.5 13790.8 13809.4 14210.7 1755.1 1771.8 1780.7 1801.8 1836.6 70721.0 72915.6 73211.3 73432.6 73460.3 9193.5 9381.4 9579.7 9726.3 9942.1 12836.4 12898.2 12905.6 12907.5 12942.2 7185.0 7323.0 7374.9 7529.4 7579.3 7357.4 7531.3 7630.8 7666.5 7682.1 5926.9 5964.9 5992.7 6040.8 6141.6 1609.8 1645.3 1669.3 1686.2 1703.3 787.8 815.4 829.5 839.2 890.6 17228.7 17523.1 17773.5 18486.2 18809.4 823.8 840.8 856.2 894.7 907.9 10001.4 10521.9 10596.4 10679.1 10726.4 364590.0 387784.0 390815.0 394008.0 396128.0 49056.8 69474.0 71518.0 72507.3 74461.3 923.1 941.7 947.4 972.3 977.9 11810.3 16522.3 17070.4 17139.5 17364.3 25601.4 28056.7 36639.7 37035.3 37839.4 39523.4 46273.0 46843.3 46918.8 48285.3 6948.0 7758.0 7861.0 7879.9 8309.0 11241.7 11847.4 11968.5 12382.8 12432.2 10431.9 10453.9 10814.9 10847.9 10920.5 10556.5 10841.1 10873.5 11084.9 11104.0 10476.6 10530.3 10668.5 10884.1 11038.3 7767.4 7942.9 8044.5 8046.2 8117.3 10546.6 10704.6 10747.3 10805.9 11386.0 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#3] mootools.html Cycles:180 Average:5271.55 Median:4865.93 stddev:2988.40 (61.4%) stddev-sans-first:2982.45 08:48:33 INFO - PROCESS | 23474 | Values: 1376.7 1434.1 1466.9 1639.4 1673.9 7634.8 8157.4 8166.9 8167.1 8167.2 6825.1 7037.2 7522.3 7754.1 8005.6 2152.1 2184.6 2185.5 2187.5 2188.2 2146.5 2165.4 2212.6 2221.6 2222.7 1801.1 1866.3 1868.2 1875.1 1877.9 2068.5 2120.1 2125.2 2137.9 2142.7 2157.7 2168.0 2174.4 2187.8 2190.9 7949.2 8061.4 8070.7 8071.8 8089.2 4641.8 4788.7 4792.3 4794.0 4797.1 2889.5 3013.4 3049.6 3078.1 3110.3 953.5 977.4 985.5 995.3 1003.0 10871.9 10930.1 10931.9 10943.9 10958.3 4128.3 4281.6 4356.0 4426.9 4434.6 9312.4 9328.5 9349.8 9355.9 9368.4 4815.0 4816.6 4818.3 4819.9 4820.1 4328.5 4365.4 4392.1 4392.7 4393.2 3678.2 3686.9 3803.6 3805.9 3807.6 1165.1 1168.9 1171.8 1176.8 1181.1 760.2 778.1 785.3 787.1 791.1 5283.4 5306.4 5476.8 5477.7 5593.8 387.1 389.3 398.0 402.7 408.6 3909.9 4033.8 4054.0 4082.3 4134.2 9872.7 9921.9 9990.6 10017.3 10023.2 9913.7 9955.4 9978.2 9979.1 9993.5 7068.6 7244.6 7398.1 7418.5 7422.0 5432.3 5561.9 5730.7 5775.5 5933.9 8795.0 8916.7 9052.6 9066.0 9382.0 9198.8 9235.2 9428.8 9520.1 9537.2 6917.4 7097.4 7106.3 7114.6 7123.5 7629.8 7632.3 7645.7 7684.8 7691.9 6877.4 6897.0 6923.4 7018.4 7127.1 6872.5 6896.4 6906.2 6921.6 6937.7 6451.0 6566.6 6568.1 6571.1 6574.7 4598.8 4648.1 4767.0 4801.6 4847.5 4710.5 4729.4 4800.8 4884.3 4978.8 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#4] prototype.html Cycles:180 Average:4239.68 Median:4814.55 stddev:1237.83 (25.7%) stddev-sans-first:1205.20 08:48:33 INFO - PROCESS | 23474 | Values: 285.5 296.8 304.0 309.5 312.1 4724.7 4825.9 4927.1 4981.4 4986.9 4768.5 4893.4 4914.9 4918.4 4921.2 3918.4 3933.9 3939.2 3956.4 3964.4 3700.6 3737.6 3872.8 3874.5 3934.8 3822.3 3824.7 3837.3 3847.5 3865.3 3838.2 3845.1 3845.2 3853.5 3866.7 3245.8 3440.3 3474.5 3476.5 3480.6 4803.7 4809.3 4809.5 4812.4 4838.8 4369.5 4387.5 4390.9 4402.9 4410.6 5007.9 5149.4 5152.9 5153.2 5175.1 3648.9 3677.6 3680.0 3680.9 3695.1 5434.7 5443.4 5461.8 5465.9 5479.0 3787.3 3788.9 3789.3 3799.8 3802.3 3733.4 3786.2 3794.2 3824.9 3831.7 4110.1 4153.0 4272.9 4293.2 4342.5 5102.7 5111.3 5115.3 5117.3 5180.5 1296.5 1304.8 1308.9 1309.0 1317.4 799.9 839.3 852.2 861.9 865.7 4056.2 4068.9 4084.4 4097.6 4098.7 4623.7 4623.7 4637.6 4692.0 4725.6 4106.4 4107.8 4113.7 4123.3 4147.7 2564.7 2635.6 2818.4 2839.2 2846.4 5012.1 5015.2 5016.4 5016.5 5028.5 5329.3 5472.6 5478.0 5501.8 5518.7 4996.0 5007.8 5015.3 5020.2 5037.4 5316.3 5324.9 5332.0 5334.5 5338.9 5013.7 5154.6 5197.8 5198.5 5203.5 4956.2 4960.4 4998.7 5003.6 5009.2 3669.1 3700.6 3724.9 3727.6 3730.4 4816.7 5159.5 5325.7 5372.7 5373.6 5106.6 5111.3 5133.2 5152.4 5158.0 5132.8 5168.1 5177.1 5178.6 5180.1 4891.8 5064.7 5067.6 5107.6 5151.6 5132.9 5189.1 5192.6 5192.6 5203.2 5063.4 5076.3 5093.4 5095.4 5101.2 08:48:33 INFO - PROCESS | 23474 | 08:48:33 INFO - PROCESS | 23474 | [#5] yui.html Cycles:180 Average:17406.28 Median:24561.04 stddev:24759.21 (100.8%) stddev-sans-first:24796.67 08:48:33 INFO - PROCESS | 23474 | Values: 642.2 645.7 657.4 722.7 749.6 12755.0 13468.4 13468.5 13471.7 13494.6 12957.5 13097.9 13120.1 13125.9 13130.8 599.5 616.2 757.6 757.8 775.1 762.6 776.3 779.8 780.2 780.4 812.8 814.9 815.0 815.1 815.2 621.6 781.8 803.6 805.7 808.1 583.5 585.1 585.3 585.3 585.6 13466.1 13516.9 13536.5 13573.0 13578.8 12427.6 12611.8 12890.0 13094.3 13334.2 11342.3 11375.7 11681.9 11743.5 12144.1 4051.8 4058.6 4115.7 4236.2 4236.4 60301.4 60367.0 60378.4 60382.7 60570.0 9824.2 10021.3 10170.2 10320.4 10575.0 18846.0 18876.7 18884.2 18944.5 18963.1 6713.8 6765.4 6808.1 6841.7 6949.4 3507.9 3567.7 3592.2 3629.5 3673.4 2753.0 2823.1 2842.9 2844.2 2846.9 496.3 502.7 503.3 505.6 509.5 484.3 499.1 508.1 551.0 554.2 7817.9 8002.8 8086.3 8087.8 8132.2 533.9 550.1 552.3 565.4 592.3 3597.7 3692.9 3724.3 3828.6 3879.4 86261.6 97391.5 98435.0 100806.5 100837.5 47843.3 48625.8 85541.6 89425.0 89646.6 73569.0 74408.6 75437.0 76207.6 76499.0 6807.5 6876.2 7139.9 7289.8 7392.2 45314.0 45382.0 46876.5 46897.8 47833.3 60147.4 63269.7 64102.4 64463.4 65884.3 9644.2 9694.4 9699.6 9946.4 10201.1 10788.3 10825.9 10855.6 11114.7 11826.6 9555.1 9594.5 9693.7 10700.4 12801.4 12035.9 12178.8 12535.0 12919.8 12992.9 10719.4 10754.2 11060.3 11346.6 11440.3 10385.1 10457.4 10989.6 11120.2 11162.9 9846.5 10025.6 10520.9 10875.6 11139.9 08:48:33 INFO - PROCESS | 23474 | -------- Summary: end -------- 08:48:33 INFO - PROCESS | 23474 | 08:48:34 INFO - TEST-INFO | 23474: exit 0 08:48:34 INFO - TEST-OK | dromaeo_css | took 1120427ms 08:48:34 INFO - TEST-START | kraken 08:48:34 INFO - operating with platform_type : linux_ 08:48:34 INFO - Initialising browser for kraken test... 08:48:34 INFO - TEST-INFO | started process 23664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTWyEHg/profile http://localhost:49705/getInfo.html) 08:48:37 INFO - PROCESS | 23664 | JavaScript error: , line 0: uncaught exception: undefined 08:48:38 INFO - PROCESS | 23664 | __metrics Screen width/height:1600/1200 08:48:38 INFO - PROCESS | 23664 | colorDepth:24 08:48:38 INFO - PROCESS | 23664 | Browser inner width/height: 1024/697 08:48:38 INFO - PROCESS | 23664 | __metrics 08:48:39 INFO - PROCESS | 23664 | 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" 08:48:39 INFO - PROCESS | 23664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:48:40 INFO - TEST-INFO | 23664: exit 0 08:48:40 INFO - Browser initialized. 08:48:40 INFO - Running cycle 1/1 for kraken test... 08:48:40 INFO - TEST-INFO | started process 23742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTWyEHg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:52 INFO - PROCESS | 23742 | RSS: Main: 159002624 08:48:52 INFO - PROCESS | 23742 | 08:48:55 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-beat-detection) 08:48:56 INFO - PROCESS | 23742 | RSS: Main: 214994944 08:48:56 INFO - PROCESS | 23742 | 08:48:58 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-dft) 08:48:59 INFO - PROCESS | 23742 | RSS: Main: 193044480 08:48:59 INFO - PROCESS | 23742 | 08:49:02 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-fft) 08:49:03 INFO - PROCESS | 23742 | RSS: Main: 273108992 08:49:03 INFO - PROCESS | 23742 | 08:49:05 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-oscillator) 08:49:06 INFO - PROCESS | 23742 | RSS: Main: 182153216 08:49:06 INFO - PROCESS | 23742 | 08:49:08 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:49:08 INFO - PROCESS | 23742 | RSS: Main: 186486784 08:49:08 INFO - PROCESS | 23742 | 08:49:12 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-darkroom) 08:49:12 INFO - PROCESS | 23742 | RSS: Main: 222765056 08:49:12 INFO - PROCESS | 23742 | 08:49:16 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-desaturate) 08:49:16 INFO - PROCESS | 23742 | RSS: Main: 222158848 08:49:16 INFO - PROCESS | 23742 | 08:49:19 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49705/tests/kraken/driver.html?testName=json-parse-financial) 08:49:20 INFO - PROCESS | 23742 | RSS: Main: 221761536 08:49:20 INFO - PROCESS | 23742 | 08:49:22 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49705/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:49:22 INFO - PROCESS | 23742 | RSS: Main: 186417152 08:49:22 INFO - PROCESS | 23742 | 08:49:24 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:49:25 INFO - PROCESS | 23742 | RSS: Main: 193400832 08:49:25 INFO - PROCESS | 23742 | 08:49:27 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:49:28 INFO - PROCESS | 23742 | RSS: Main: 196784128 08:49:28 INFO - PROCESS | 23742 | 08:49:30 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:49:31 INFO - PROCESS | 23742 | RSS: Main: 192385024 08:49:31 INFO - PROCESS | 23742 | 08:49:34 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:49:34 INFO - PROCESS | 23742 | RSS: Main: 195518464 08:49:34 INFO - PROCESS | 23742 | 08:49:36 INFO - PROCESS | 23742 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49705/tests/kraken/driver.html?testName=ai-astar) 08:49:36 INFO - PROCESS | 23742 | RSS: Main: 246431744 08:49:36 INFO - PROCESS | 23742 | 08:49:36 INFO - PROCESS | 23742 | __start_tp_report 08:49:36 INFO - PROCESS | 23742 | _x_x_mozilla_page_load 08:49:37 INFO - PROCESS | 23742 | _x_x_mozilla_page_load_details 08:49:37 INFO - PROCESS | 23742 | |i|pagename|runs| 08:49:37 INFO - PROCESS | 23742 | |0;ai-astar;104;114;114;112;112;112;112;112;112;112 08:49:37 INFO - PROCESS | 23742 | |1;audio-beat-detection;155;123;122;185;126;130;151;123;178;122 08:49:37 INFO - PROCESS | 23742 | |2;audio-dft;161;160;160;160;159;160;161;161;164;162 08:49:37 INFO - PROCESS | 23742 | |3;audio-fft;76;75;75;121;75;76;75;76;115;75 08:49:37 INFO - PROCESS | 23742 | |4;audio-oscillator;85;85;109;85;86;85;125;85;85;127 08:49:37 INFO - PROCESS | 23742 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;116;117;116 08:49:37 INFO - PROCESS | 23742 | |6;imaging-darkroom;147;146;145;146;147;145;146;146;146;146 08:49:37 INFO - PROCESS | 23742 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;96 08:49:37 INFO - PROCESS | 23742 | |8;json-parse-financial;62;61;60;61;60;61;61;60;61;62 08:49:37 INFO - PROCESS | 23742 | |9;json-stringify-tinderbox;66;66;66;67;66;67;67;66;67;66 08:49:37 INFO - PROCESS | 23742 | |10;stanford-crypto-aes;78;90;79;78;89;85;78;88;78;82 08:49:37 INFO - PROCESS | 23742 | |11;stanford-crypto-ccm;131;132;137;143;126;135;127;128;181;132 08:49:37 INFO - PROCESS | 23742 | |12;stanford-crypto-pbkdf2;169;172;162;164;174;167;166;165;163;163 08:49:37 INFO - PROCESS | 23742 | |13;stanford-crypto-sha256-iterative;68;73;67;66;67;76;66;66;66;66 08:49:37 INFO - PROCESS | 23742 | __end_tp_report 08:49:37 INFO - PROCESS | 23742 | __start_cc_report 08:49:37 INFO - PROCESS | 23742 | _x_x_mozilla_cycle_collect,1985 08:49:37 INFO - PROCESS | 23742 | __end_cc_report 08:49:37 INFO - PROCESS | 23742 | __startTimestamp1469893776997__endTimestamp 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | ------- Summary: start ------- 08:49:37 INFO - PROCESS | 23742 | Number of tests: 14 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:2.80 (2.5%) stddev-sans-first:0.88 08:49:37 INFO - PROCESS | 23742 | Values: 104.0 114.0 114.0 112.0 112.0 112.0 112.0 112.0 112.0 112.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:140.50 stddev:24.30 (17.3%) stddev-sans-first:25.28 08:49:37 INFO - PROCESS | 23742 | Values: 155.0 123.0 122.0 185.0 126.0 130.0 151.0 123.0 178.0 122.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.40 (0.9%) stddev-sans-first:1.48 08:49:37 INFO - PROCESS | 23742 | Values: 161.0 160.0 160.0 160.0 159.0 160.0 161.0 161.0 164.0 162.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#3] audio-fft Cycles:10 Average:83.90 Median:75.00 stddev:18.03 (24.0%) stddev-sans-first:18.90 08:49:37 INFO - PROCESS | 23742 | Values: 76.0 75.0 75.0 121.0 75.0 76.0 75.0 76.0 115.0 75.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:85.00 stddev:17.63 (20.7%) stddev-sans-first:18.27 08:49:37 INFO - PROCESS | 23742 | Values: 85.0 85.0 109.0 85.0 86.0 85.0 125.0 85.0 85.0 127.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:49:37 INFO - PROCESS | 23742 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 08:49:37 INFO - PROCESS | 23742 | Values: 147.0 146.0 145.0 146.0 147.0 145.0 146.0 146.0 146.0 146.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:49:37 INFO - PROCESS | 23742 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 08:49:37 INFO - PROCESS | 23742 | Values: 62.0 61.0 60.0 61.0 60.0 61.0 61.0 60.0 61.0 62.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:49:37 INFO - PROCESS | 23742 | Values: 66.0 66.0 66.0 67.0 66.0 67.0 67.0 66.0 67.0 66.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.50 stddev:5.04 (6.0%) stddev-sans-first:5.07 08:49:37 INFO - PROCESS | 23742 | Values: 78.0 90.0 79.0 78.0 89.0 85.0 78.0 88.0 78.0 82.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:133.50 stddev:16.21 (12.1%) stddev-sans-first:17.03 08:49:37 INFO - PROCESS | 23742 | Values: 131.0 132.0 137.0 143.0 126.0 135.0 127.0 128.0 181.0 132.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:166.50 stddev:4.03 (2.4%) stddev-sans-first:4.18 08:49:37 INFO - PROCESS | 23742 | Values: 169.0 172.0 162.0 164.0 174.0 167.0 166.0 165.0 163.0 163.0 08:49:37 INFO - PROCESS | 23742 | 08:49:37 INFO - PROCESS | 23742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:67.00 stddev:3.51 (5.2%) stddev-sans-first:3.72 08:49:37 INFO - PROCESS | 23742 | Values: 68.0 73.0 67.0 66.0 67.0 76.0 66.0 66.0 66.0 66.0 08:49:37 INFO - PROCESS | 23742 | -------- Summary: end -------- 08:49:37 INFO - PROCESS | 23742 | 08:49:38 INFO - TEST-INFO | 23742: exit 0 08:49:38 INFO - TEST-OK | kraken | took 64078ms 08:49:38 INFO - SUITE-END | took 1184s 08:49:38 INFO - Completed test suite (00:19:44) 08:49:38 INFO - javascript benchmark 08:49:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.5466870631994, 2672.171626795692, 2676.4513274778674, 2684.087410280504, 2715.0497325935926, 4654.3716598259225, 4733.8343157989475, 4889.364382630258, 4958.7768550415185, 4961.702297862126, 4817.807287708496, 4869.196582563882, 4881.292212629167, 4896.485868983759, 4944.901101977958, 4416.73499590024, 4461.5092339842595, 4515.119551687422, 4529.426371989074, 4591.196540605398, 4511.255642818947, 4524.705894116888, 4557.886052848688, 4574.634029277667, 4596.678232523725, 4314.762688052156, 4344.804483798237, 4347.260965635849, 4412.029353542215, 4476.597106260419, 4362.47650281965, 4429.977850110761, 4445.533219012022, 4450.1767173073085, 4458.77706114693, 4391.3193455014525, 4453.354263631755, 4469.7318160910445, 4478.57453541913, 4523.321501774727, 4749.287606858964, 4876.341693871317, 4926.5812405945535, 4955.033768415161, 4962.0324036812835, 5160.200168973815, 5212.582993360522, 5227.398849132361, 5243.213517972296, 5316.229146773696, 5278.577713782888, 5286.0, 5315.867103322448, 5315.973420132913, 5348.812791552277, 4716.787744551435, 4781.330613714083, 4928.457869634337, 4944.0111977604265, 4967.751612419364, 5258.763355649005, 5309.65487243328, 5315.04329220744, 5388.2725832012175, 5409.83770486886, 5099.796008159632, 5120.846374608767, 5159.509846564571, 5167.974160129251, 5170.71561064145, 5056.317397151411, 5245.213218017251, 5267.367915850123, 5273.736313184326, 5275.788968441219, 5020.322256495399, 5068.138416469209, 5109.4379618241965, 5111.233315002779, 5177.948220517822, 5087.389513258433, 5094.108531007059, 5105.402008177812, 5109.20807274873, 5111.3355263815465, 4356.934645980313, 4370.453693288339, 4375.453068366454, 4403.097365040159, 4426.822927082881, 3751.1934933989323, 3773.8867833965023, 3792.3742582474133, 3816.675582575512, 3818.8663396781535, 4498.527654596286, 4503.234450143484, 4535.4103966484145, 4539.72761634303, 4546.227141385973, 5175.223716442564, 5176.171812509981, 5212.592599998055, 5220.112580861262, 5274.7890084396195, 4368.301071828492, 4437.467503899488, 4501.234790085693, 4503.774811259424, 4552.84065057728, 4647.930281045787, 4732.1009008289, 4737.549932756383, 4738.0, 4739.834105806349, 4843.806247750051, 4850.733209673502, 4858.46556878743, 4860.927086093782, 4911.435184953776, 5264.657797243167, 5286.0, 5287.312649355559, 5295.152775556046, 5297.602679799107, 4681.672282940298, 4692.906141877075, 4818.68678535894, 4862.82980095695, 4958.454569997225, 5165.457626949116, 5165.535101840853, 5166.4316925138955, 5207.453217412269, 5218.634695571425, 5237.921431178612, 5252.711100889335, 5254.343207099113, 5262.657927234717, 5265.262863199078, 4963.305137280711, 5061.342025536656, 5090.847274581768, 5105.05556472039, 5114.74426278692, 5128.435872054146, 5134.512221338967, 5145.305383773292, 5172.456892026283, 5191.662541934913, 5044.520770526794, 5050.173869846001, 5091.083604950996, 5103.081445339874, 5103.642745007814, 4940.0, 5068.898622027613, 5122.231665250093, 5131.4868513148385, 5183.663061900965, 5038.445770965118, 5042.168042273131, 5042.823501177441, 5075.3402057732255, 5087.287779710918], "value": 4780.1111747629375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.72054484384, 564.8644325361912, 581.4824805922729, 587.4272584230374, 597.1878245585999, 26920.730792692066, 40137.3979390309, 41525.16949661005, 41556.376654350206, 41655.375169372484, 41928.580714192845, 41977.0, 42245.0, 42266.7886660567, 42270.36594451086, 7441.404687624977, 7775.8056048598955, 7933.603319834014, 7947.08608510023, 9614.42313461191, 9800.166985806214, 10196.74508137295, 10196.898031019706, 10197.643082492114, 10207.234457415687, 10228.43743594102, 10278.177745780356, 10287.485625718684, 10293.073623373895, 10297.845532317022, 10168.4915754212, 10233.692989210336, 10233.846492302622, 10243.539040743184, 10243.84634230487, 9950.452725100116, 10007.59969601215, 10021.348612340174, 10021.949890250502, 10026.899731002743, 39745.60254397448, 39996.20007599861, 40108.799456002824, 40132.19735605301, 40164.59835401638, 3070.385922815446, 3076.9076927692204, 3130.1392117167734, 3151.149189718786, 3153.7950033247766, 29027.564586531218, 29488.70511294881, 29492.0, 29501.409971800444, 31055.844720776477, 3327.284633803744, 3379.459286514146, 3381.594208694972, 3388.3562123196516, 3451.9309613807836, 265968.0, 266060.0, 266262.0, 266692.0, 267214.0, 32383.352332953207, 54215.72892135593, 54568.45431545713, 54584.72707636516, 54602.45397546053, 32980.50529242063, 34290.82854585711, 34346.0, 34526.136846578534, 34549.827250863586, 13400.195988240737, 13548.661283467993, 13556.728865422636, 13562.660933476742, 13562.728745425036, 10094.848577271347, 10945.56217751297, 10996.450177491093, 11228.943855280671, 11271.380074096003, 7941.245581669732, 7968.12350641429, 7981.281684648294, 8006.079300880357, 8064.879026814602, 3036.8633411496535, 3069.227351468488, 3111.5177147542167, 3124.265797537577, 3140.4347217501067, 786.5949036246337, 789.2186735132178, 832.3050253038781, 921.7465197070859, 944.0983860413289, 27316.45367092647, 28687.0, 29095.27261818429, 29578.0, 29718.702812971595, 887.3965703321803, 891.6789955615973, 899.7930475990559, 912.8539433690578, 915.5696822493418, 14543.3455494503, 15266.236688165547, 15280.465183718516, 15361.846381536265, 15677.137757423452, 424913.0, 429888.0, 430181.0, 430219.0, 431154.0, 50662.0, 51182.74408628007, 51241.7437912808, 51256.74371628118, 51288.74355628198, 991.9931269761198, 1062.8671416072991, 1094.496531595475, 1144.2791041643877, 1144.6337172104847, 27678.0, 29248.41503169882, 29535.113946581638, 29904.551431728105, 30120.84939575288, 35631.0, 35988.64011359853, 36101.638983609824, 36195.0, 36203.09492262715, 319894.0, 321199.0, 321648.0, 322544.0, 323686.0, 24673.753262467144, 25312.873435632704, 25493.61759573645, 25528.872355638847, 26527.0, 42199.7890010548, 42482.0, 42930.35604465998, 43113.0, 43165.92085197895, 36491.63508364883, 36711.0, 36953.26093478169, 37537.81231093827, 37879.62120378871, 36165.457518136725, 36862.63137368592, 36906.63093369139, 37003.07492312714, 37503.43744843884, 35770.28459430745, 36301.45547816838, 36437.63562364342, 36494.45258321074, 37171.0, 32063.67936320607, 33706.60694288902, 33888.0, 34018.31963360769, 34247.486287706204, 25791.0, 25849.870750646125, 25885.482290354463, 25917.6112358311, 26269.605955910294], "value": 17052.499987220803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.57108327840024, 370.34469783239433, 380.63839352615025, 384.45599476740404, 399.49862922032844, 12387.38063096845, 12715.618531444059, 17107.486775396726, 17127.229274682624, 17130.914345428257, 15887.285072171751, 16354.100524471149, 17158.571035724082, 17451.1274436278, 17522.82477175228, 3839.520059992501, 5161.483851614837, 5316.282301889234, 5345.251664766935, 5351.25082488452, 5908.88182236355, 6186.412290832363, 6256.843578910518, 6350.364963503635, 6353.30113687494, 6112.908306375386, 6220.844478888019, 6286.182796236483, 6327.46216571592, 6357.68211589421, 6308.905366419507, 6347.143135676671, 6354.332795056545, 6357.968210158966, 6359.459445947077, 5343.385510666284, 5438.945610543883, 5468.179773034037, 5472.1244600864065, 5477.945220547823, 20801.5839683207, 21262.787372126237, 21312.573748525097, 21446.463838404008, 21478.677819832716, 1888.5656299051159, 1890.8960007199598, 1893.2616279650947, 1987.6124155789628, 2450.816188785848, 13132.934335328262, 13645.522406715816, 13790.793138102936, 13809.378577963915, 14210.715785684331, 1755.139981409113, 1771.7873855137466, 1780.6883795335764, 1801.8018018018008, 1836.6326734653123, 70721.0, 72915.63542182255, 73211.2678873215, 73432.63283683601, 73460.26539734642, 9193.49435781025, 9381.43711377306, 9579.664711735057, 9726.297897867846, 9942.105210531086, 12836.358182090858, 12898.161619494704, 12905.612831615066, 12907.548235811702, 12942.223466592035, 7184.9640751795905, 7323.0, 7374.9262507375315, 7529.397648188241, 7579.317861392501, 7357.41140708742, 7531.284527969834, 7630.809229769189, 7666.501677390952, 7682.078150621961, 5926.911096333559, 5964.910526342108, 5992.700364981733, 6040.788572399945, 6141.57009009374, 1609.8148712897932, 1645.3336398758522, 1669.348953907977, 1686.1990554486522, 1703.3442124782048, 787.8069872881181, 815.4291995603102, 829.5188790501495, 839.2488722593245, 890.6259371064168, 17228.655426891644, 17523.12384380776, 17773.46679599614, 18486.168122434523, 18809.435716928514, 823.8022874510075, 840.8192238668715, 856.2336708645727, 894.7494701483595, 907.9273658107336, 10001.449920254456, 10521.894781052091, 10596.350912271932, 10679.092277156373, 10726.410047447465, 364590.0, 387784.0, 390815.0, 394008.0, 396128.0, 49056.75471622619, 69474.0, 71518.0, 72507.27492725005, 74461.25538744651, 923.1091996223677, 941.7316064921602, 947.393668052447, 972.2853702528636, 977.8630991661031, 11810.319117208539, 16522.339106435607, 17070.402535911184, 17139.485815425556, 17364.305427783253, 25601.359966001, 28056.71943280623, 36639.70242678172, 37035.25929481449, 37839.43240851334, 39523.40714889336, 46273.0, 46843.29735053909, 46918.76540617275, 48285.275720863516, 6947.957806329091, 7758.0, 7861.0, 7879.881801773093, 8309.04445988719, 11241.718957025812, 11847.407629618658, 11968.521259149884, 12382.752344953231, 12432.191907525981, 10431.94784026075, 10453.9477302613, 10814.945925270324, 10847.945760271148, 10920.50857711389, 10556.524956377136, 10841.07850832688, 10873.456327183767, 11084.889151108708, 11104.0, 10476.580936762444, 10530.262881598215, 10668.46657667098, 10884.129269658566, 11038.282511636718, 7767.378609711097, 7942.920570794449, 8044.517328960281, 8046.235607617267, 8117.310028647631, 10546.630867919586, 10704.571817127227, 10747.301425407324, 10805.891941080488, 11386.032187263843], "value": 8496.904023598163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.6558360409897, 1434.118017419287, 1466.8753155981742, 1639.4425895195645, 1673.8995660260377, 7634.847303053943, 8157.38819588531, 8166.877496837537, 8167.101618821925, 8167.224113709188, 6825.078614387056, 7037.2259051504425, 7522.285382888631, 7754.108277548084, 8005.559694216816, 2152.0638522242834, 2184.563087382522, 2185.530111026129, 2187.540616470535, 2188.1794327127327, 2146.4848436375314, 2165.4261620670536, 2212.590670725913, 2221.611218036845, 2222.7221597300336, 1801.1354549816097, 1866.2628261836567, 1868.2060124447055, 1875.1093230715437, 1877.8685492015563, 2068.4725395024248, 2120.0883620043373, 2125.203048856679, 2137.9144834206613, 2142.678598210265, 2157.6871353653787, 2168.0, 2174.4346469917778, 2187.84685072045, 2190.87950162741, 7949.244821741925, 8061.355091592659, 8070.71752488666, 8071.798205044862, 8089.2315230053055, 4641.791119399635, 4788.664793464459, 4792.281157826319, 4793.976030119862, 4797.0645724083815, 2889.4510043091746, 3013.382256637384, 3049.6340439147443, 3078.122735020531, 3110.2535391506103, 953.5232383808096, 977.4233202410541, 985.4727720669479, 995.2533304051416, 1002.989970100297, 10871.945640271828, 10930.125589952784, 10931.945340273247, 10943.890561094368, 10958.287711298819, 4128.277551428488, 4281.636060934793, 4355.956440435619, 4426.911461770811, 4434.600885920283, 9312.394694344846, 9328.533573321307, 9349.766255843522, 9355.90644093564, 9368.39105458143, 4814.9518504814505, 4816.638752093607, 4818.325434439182, 4819.927701084486, 4820.132376172252, 4328.523862375137, 4365.388845561624, 4392.05570802279, 4392.736435813861, 4393.18726035678, 3678.1540245743627, 3686.870959516458, 3803.6006219347123, 3805.8667946621736, 3807.6192380762254, 1165.143619439711, 1168.8889555492215, 1171.8359429679851, 1176.7528818948117, 1181.1141643767176, 760.2207737069548, 778.116837389567, 785.340314136117, 787.1144961917843, 791.133708589094, 5283.418823929364, 5306.442823503554, 5476.835694929158, 5477.7261136943, 5593.804216852391, 387.11157892636464, 389.28371795895123, 397.9840806367713, 402.6617641181422, 408.5914085914086, 3909.863154789569, 4033.8184781684895, 4053.9594604054173, 4082.265192265421, 4134.214499245134, 9872.703818885446, 9921.851172232422, 9990.600375985025, 10017.348872323275, 10023.198144148451, 9913.702588922344, 9955.402675839472, 9978.201743860474, 9979.103970245775, 9993.450360230112, 7068.646567671596, 7244.565326080452, 7398.075240594926, 7418.480706350611, 7421.962890185515, 5432.348118225838, 5561.944380556142, 5730.71346432685, 5775.451332123442, 5933.881322373442, 8795.0, 8916.687915922912, 9052.592633331647, 9065.954670226607, 9381.953090234505, 9198.770030749285, 9235.215006724235, 9428.811423771349, 9520.095590918852, 9537.23702103844, 6917.446604271546, 7097.396721278749, 7106.289371062852, 7114.6442677866935, 7123.465740069619, 7629.771106866803, 7632.313091821763, 7645.732399365996, 7684.807879802826, 7691.923080769121, 6877.415419689382, 6896.965515172392, 6923.446124310144, 7018.368346848604, 7127.10911136108, 6872.450203983769, 6896.448284137357, 6906.205786334636, 6921.584704917721, 6937.722491100501, 6450.967745161244, 6566.606003639797, 6568.146141001639, 6571.112899758471, 6574.737010519717, 4598.770061496979, 4648.093621743727, 4766.976165119152, 4801.567858892717, 4847.515248475265, 4710.505396933273, 4729.385179926588, 4800.807967681393, 4884.340614017062, 4978.751062446936], "value": 4139.440520617864, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.5260267955195, 296.842673383107, 303.9908802735918, 309.4569031349981, 312.0560305077141, 4724.716517008982, 4825.927611085832, 4927.117636182828, 4981.377327834025, 4986.927810520738, 4768.451628062774, 4893.388326459192, 4914.901701965968, 4918.376935611249, 4921.237208232729, 3918.412238164277, 3933.8819835404984, 3939.172773717523, 3956.3669812829953, 3964.405339199114, 3700.5559332880093, 3737.6266071044947, 3872.7869967151937, 3874.5156855393075, 3934.783586902719, 3822.273767984088, 3824.6557809797114, 3837.34765089934, 3847.480590120326, 3865.3042452358563, 3838.2323535293053, 3845.0964023454467, 3845.192509572993, 3853.537575490931, 3866.6520013198665, 3245.8052516849066, 3440.2947395783804, 3474.5309383233193, 3476.5480487536456, 3480.5823301203764, 4803.687760295569, 4809.292701091097, 4809.5430934061205, 4812.422509298872, 4838.830640927551, 4369.497507786582, 4387.451568553931, 4390.868273951754, 4402.8679139625865, 4410.62509686677, 5007.89826238227, 5149.407818100929, 5152.896942061176, 5153.2270159476, 5175.094358487249, 3648.9270214595826, 3677.613850545681, 3679.963200367989, 3680.9447858282147, 3695.097025032084, 5434.701091439969, 5443.428440013781, 5461.836144915659, 5465.939074118467, 5478.972605136989, 3787.3182827091387, 3788.886333410002, 3789.2758794748324, 3799.8290076946605, 3802.2775672622115, 3733.439984002421, 3786.1670432504807, 3794.2221844521805, 3824.9426258606145, 3831.6743076838225, 4110.136871257009, 4153.0, 4272.850450234227, 4293.184294983943, 4342.457192850894, 5102.719350435687, 5111.3355263815465, 5115.335006449212, 5117.309163262987, 5180.507851754077, 1296.4684479363416, 1304.8042793580848, 1308.9345532723326, 1309.0247765414636, 1317.413750880868, 799.9480033797901, 839.261449924063, 852.1733918099434, 861.8750281209315, 865.7143142762873, 4056.241755606216, 4068.898277543026, 4084.3873418986796, 4097.569755175723, 4098.692598055158, 4623.676342656049, 4623.734370158598, 4637.60580350674, 4692.0, 4725.5510726480925, 4106.3635136553885, 4107.753534787863, 4113.650339721157, 4123.340265557556, 4147.730397524151, 2564.6794150731157, 2635.5783074707956, 2818.3517790908204, 2839.1766387747734, 2846.4164846206477, 5012.147934851084, 5015.22264049073, 5016.372953380827, 5016.473270306638, 5028.472010438924, 5329.307190065344, 5472.5621950243885, 5477.972610137003, 5501.807436739695, 5518.668879867221, 4995.950040499694, 5007.799688012512, 5015.272785446163, 5020.246962955513, 5037.3955125384455, 5316.308879845596, 5324.946750532445, 5332.0004998125705, 5334.493223143795, 5338.8932221356135, 5013.674111182761, 5154.61340399464, 5197.792088316425, 5198.4801519847715, 5203.505666961632, 4956.231784073474, 4960.404751429852, 4998.700077995332, 5003.599712023102, 5009.223570346602, 3669.1194113412057, 3700.592934777226, 3724.888253352404, 3727.6458736419995, 3730.421784623388, 4816.686915350491, 5159.458256882973, 5325.680459172462, 5372.704501252312, 5373.596980226577, 5106.595686186329, 5111.284420181252, 5133.204353325241, 5152.381714194321, 5158.0, 5132.769025393792, 5168.095583272988, 5177.145770947752, 5178.559822415137, 5180.145276029563, 4891.755412229445, 5064.696118232918, 5067.6452648315735, 5107.616928730435, 5151.6393852431465, 5132.923006154836, 5189.143791274398, 5192.610554208374, 5192.610554208525, 5203.245529398292, 5063.417706963746, 5076.340075790124, 5093.351457242139, 5095.388553373619, 5101.183810590288], "value": 3845.221344540618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.2325321241119, 645.7158850105955, 657.3853447027033, 722.6892436252413, 749.6102026945987, 12755.0, 13468.393922273497, 13468.528601498949, 13471.66320841977, 13494.595162145139, 12957.546485872996, 13097.869021309807, 13120.147190432615, 13125.93437032816, 13130.803037954438, 599.5084031094505, 616.22355831652, 757.5757575757566, 757.8219118507174, 775.1008829757483, 762.6186906546727, 776.2935728487078, 779.7972527142927, 780.239266714954, 780.3756994404482, 812.808989887376, 814.9429539932207, 814.9837003259931, 815.0789607743271, 815.233680340481, 621.5711159300114, 781.7732857471326, 803.6062329458553, 805.697863301262, 808.0505406147753, 583.5390041866963, 585.1047896718028, 585.28887401807, 585.3268740947901, 585.5959388022251, 13466.124701894345, 13516.93241533786, 13536.526221582295, 13573.049886507948, 13578.796318055238, 12427.627171184879, 12611.810822837664, 12890.0, 13094.279814610192, 13334.19994800315, 11342.319460832294, 11375.715607109805, 11681.94159029208, 11743.530258789639, 12144.149909506337, 4051.7771522566245, 4058.6447344895487, 4115.691323150746, 4236.173946080516, 4236.406903033578, 60301.39698603002, 60367.0, 60378.39621603772, 60382.698086509285, 60570.0, 9824.214062874953, 10021.34861234032, 10170.237232207613, 10320.43237621938, 10575.048245657774, 18846.0, 18876.716849247274, 18884.24463021491, 18944.526386840163, 18963.146658400405, 6713.832154196087, 6765.42493888025, 6808.148981377328, 6841.657917104125, 6949.3745562899585, 3507.9298414031578, 3567.7324200685052, 3592.191756854687, 3629.491871138043, 3673.3837111671314, 2753.0, 2823.082498188097, 2842.928926776847, 2844.1609725131134, 2846.8718907649204, 496.3324328777788, 502.6581924291517, 503.32554377134085, 505.5702652745137, 509.54141272853985, 484.32678576778306, 499.11656368228034, 508.06008883565846, 550.9752061157258, 554.2462251338177, 7817.921820781833, 8002.759917202493, 8086.312663423557, 8087.757367278873, 8132.227438393344, 533.8585274902154, 550.0758725341465, 552.2930648769582, 565.3893794701712, 592.3217915486744, 3597.748157628941, 3692.8707495237536, 3724.348239058208, 3828.559715632738, 3879.398693202558, 86261.56869215614, 97391.51304243434, 98435.0, 100806.49596751969, 100837.49581252194, 47843.28235076477, 48625.75687121542, 85541.57229213814, 89425.0, 89646.55176724074, 73569.0, 74408.62795685987, 75437.0, 76207.61896190484, 76499.0, 6807.489438292049, 6876.243613202444, 7139.892901606584, 7289.778430285799, 7392.223816499405, 45314.0, 45382.0, 46876.531234687216, 46897.76551117223, 47833.28250076182, 60147.39852601418, 63269.68365158145, 64102.35897640964, 64463.35536644761, 65884.34115658974, 9644.18024467928, 9694.418334899929, 9699.563519641491, 9946.403215807075, 10201.132903702972, 10788.298760580537, 10825.863284355344, 10855.565777368816, 11114.666560003212, 11826.64520064365, 9555.140037396668, 9594.472304023346, 9693.660721874701, 10700.357978521313, 12801.42393592272, 12035.939820301193, 12178.817317740064, 12535.0, 12919.806202906775, 12992.870071299167, 10719.410432426292, 10754.24720269605, 11060.281081729661, 11346.602868899548, 11440.313581185155, 10385.065344119066, 10457.424841633783, 10989.61536346224, 11120.214211493834, 11162.944185279022, 9846.458444785605, 10025.598976040877, 10520.947395262974, 10875.564977400816, 11139.944300278446], "value": 6451.194685602917, "unit": "score"}], "name": "dromaeo_css", "value": 6436.843465672789, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 122.0, 185.0, 126.0, 130.0, 151.0, 123.0, 178.0, 122.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 159.0, 160.0, 161.0, 161.0, 164.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 121.0, 75.0, 76.0, 75.0, 76.0, 115.0, 75.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 109.0, 85.0, 86.0, 85.0, 125.0, 85.0, 85.0, 127.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 62.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 90.0, 79.0, 78.0, 89.0, 85.0, 78.0, 88.0, 78.0, 82.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 137.0, 143.0, 126.0, 135.0, 127.0, 128.0, 181.0, 132.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 162.0, 164.0, 174.0, 167.0, 166.0, 165.0, 163.0, 163.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 67.0, 66.0, 67.0, 76.0, 66.0, 66.0, 66.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1532.9, "alertThreshold": 2.0}]} 08:49:38 INFO - Return code: 0 08:49:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:49:38 INFO - # TBPL SUCCESS # 08:49:38 INFO - Running post-action listener: _resource_record_post_action 08:49:38 INFO - [mozharness: 2016-07-30 15:49:38.310811Z] Finished run-tests step (success) 08:49:38 INFO - Running post-run listener: _resource_record_post_run 08:49:38 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553701376; Read time: 16; Write time: 469404 08:49:38 INFO - TinderboxPrint: CPU usage
13.0% 08:49:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:49:38 INFO - TinderboxPrint: I/O write bytes / time
553,701,376 / 469,404 08:49:38 INFO - TinderboxPrint: CPU idle
8,337.5 (87.0%) 08:49:38 INFO - TinderboxPrint: CPU user
1,202.6 (12.5%) 08:49:38 INFO - TinderboxPrint: Swap in / out
0 / 0 08:49:38 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246714368; Read time: 0; Write time: 264356 08:49:38 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289173504; Read time: 16; Write time: 194192 08:49:38 INFO - Running post-run listener: _upload_blobber_files 08:49:38 INFO - Blob upload gear active. 08:49:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:49:38 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 08:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:49:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:40 INFO - (blobuploader) - INFO - Done attempting. 08:49:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:49:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:44 INFO - (blobuploader) - INFO - Done attempting. 08:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:45 INFO - (blobuploader) - INFO - Done attempting. 08:49:45 INFO - (blobuploader) - INFO - Iteration through files over. 08:49:45 INFO - Return code: 0 08:49:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:49:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:49:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/93bddf04b514e33f5a7d383e570c6a4ca2592129149f45fc2d40576e177203a9f1f5849d18cad3dffce4b5357f1950af79226b23f1655fab7383a6f442ed6798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e25d60968d19cce461c86d677b7876d2398389a50cb3dc5393a2583172a881c5568ba9511a63375c05b16aeed58667383777f083cf752a77372e28d35c057808", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa745b5e230a6a11ae6e1165795d2f411c29efcfa96e40c07b2c77ec5be22e622739960e8f0476dbbd90b26edc0cd0ad34d76d28b134e2a76fe8cfb42def97c"} 08:49:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:49:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:49:45 INFO - Contents: 08:49:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/93bddf04b514e33f5a7d383e570c6a4ca2592129149f45fc2d40576e177203a9f1f5849d18cad3dffce4b5357f1950af79226b23f1655fab7383a6f442ed6798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e25d60968d19cce461c86d677b7876d2398389a50cb3dc5393a2583172a881c5568ba9511a63375c05b16aeed58667383777f083cf752a77372e28d35c057808", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa745b5e230a6a11ae6e1165795d2f411c29efcfa96e40c07b2c77ec5be22e622739960e8f0476dbbd90b26edc0cd0ad34d76d28b134e2a76fe8cfb42def97c"} 08:49:45 INFO - Running post-run listener: copy_logs_to_upload_dir 08:49:45 INFO - Copying logs to upload dir... 08:49:45 INFO - mkdir: /builds/slave/test/build/upload/logs 08:49:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.229962 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-30 08:49:44.991158) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 08:49:44.994790) ========= 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=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/93bddf04b514e33f5a7d383e570c6a4ca2592129149f45fc2d40576e177203a9f1f5849d18cad3dffce4b5357f1950af79226b23f1655fab7383a6f442ed6798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e25d60968d19cce461c86d677b7876d2398389a50cb3dc5393a2583172a881c5568ba9511a63375c05b16aeed58667383777f083cf752a77372e28d35c057808", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa745b5e230a6a11ae6e1165795d2f411c29efcfa96e40c07b2c77ec5be22e622739960e8f0476dbbd90b26edc0cd0ad34d76d28b134e2a76fe8cfb42def97c"} build_url:https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011363 build_url: 'https://queue.taskcluster.net/v1/task/PmMJbhK2RlSy-4InxfOFBw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/93bddf04b514e33f5a7d383e570c6a4ca2592129149f45fc2d40576e177203a9f1f5849d18cad3dffce4b5357f1950af79226b23f1655fab7383a6f442ed6798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e25d60968d19cce461c86d677b7876d2398389a50cb3dc5393a2583172a881c5568ba9511a63375c05b16aeed58667383777f083cf752a77372e28d35c057808", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8fa745b5e230a6a11ae6e1165795d2f411c29efcfa96e40c07b2c77ec5be22e622739960e8f0476dbbd90b26edc0cd0ad34d76d28b134e2a76fe8cfb42def97c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 08:49:45.020186) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:49:45.020529) ========= 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=836cfdc75598d54cb747368e000001a9-1469890633.169711-2058517157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004508 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:49:45.102384) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 08:49:45.102721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 08:49:45.103065) ========= ========= Total master_lag: 0.21 =========