builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1464153013.77 results: success (0) buildid: 20160524193102 builduid: fd7b3a4e974347269d7a5ee2113ce351 revision: ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:13.772820) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:13.773268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:13.773889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:13.818732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:13.819069) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-24 22:10:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-05-24 22:10:14 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154268 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.248072) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.248358) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019956 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.306606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.306927) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec --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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-24 22:10:14,546 truncating revision to first 12 chars 2016-05-24 22:10:14,546 Setting DEBUG logging. 2016-05-24 22:10:14,546 attempt 1/10 2016-05-24 22:10:14,546 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ba0982c4e30c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 22:10:14,789 unpacking tar archive at: mozilla-inbound-ba0982c4e30c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.533503 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.865063) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.865337) ========= script_repo_revision: ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.865711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.866020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 22:10:14.879813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-24 22:10:14.880078) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:10:15 INFO - MultiFileLogger online at 20160524 22:10:15 in /builds/slave/test 22:10:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:10:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:15 INFO - {'append_to_log': False, 22:10:15 INFO - 'base_work_dir': '/builds/slave/test', 22:10:15 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:10:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:15 INFO - 'branch': 'Mozilla-Inbound', 22:10:15 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:15 INFO - 'config_files': ('talos/linux_config.py',), 22:10:15 INFO - 'default_actions': ('clobber', 22:10:15 INFO - 'read-buildbot-config', 22:10:15 INFO - 'download-and-extract', 22:10:15 INFO - 'populate-webroot', 22:10:15 INFO - 'create-virtualenv', 22:10:15 INFO - 'install', 22:10:15 INFO - 'run-tests'), 22:10:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:15 INFO - 'download_minidump_stackwalk': True, 22:10:15 INFO - 'download_symbols': 'ondemand', 22:10:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:15 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:15 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:15 INFO - 'installer_path': 'installer.exe', 22:10:15 INFO - 'log_level': 'info', 22:10:15 INFO - 'log_name': 'talos', 22:10:15 INFO - 'log_to_console': True, 22:10:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:10:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:10:15 INFO - 'opt_config_files': (), 22:10:15 INFO - 'pip_index': False, 22:10:15 INFO - 'sps_profile': False, 22:10:15 INFO - 'sps_profile_interval': 0, 22:10:15 INFO - 'suite': 'dromaeojs', 22:10:15 INFO - 'system_bits': '32', 22:10:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:15 INFO - 'title': 'talos-linux64-ix-064', 22:10:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:15 INFO - 'use_talos_json': True, 22:10:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:15 INFO - 'work_dir': 'build'} 22:10:15 INFO - ##### 22:10:15 INFO - ##### Running clobber step. 22:10:15 INFO - ##### 22:10:15 INFO - Running pre-action listener: _resource_record_pre_action 22:10:15 INFO - Running main action method: clobber 22:10:15 INFO - rmtree: /builds/slave/test/build 22:10:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:10:16 INFO - Running post-action listener: _resource_record_post_action 22:10:16 INFO - ##### 22:10:16 INFO - ##### Finished clobber step (success) 22:10:16 INFO - ##### 22:10:16 INFO - ##### 22:10:16 INFO - ##### Running read-buildbot-config step. 22:10:16 INFO - ##### 22:10:16 INFO - Running pre-action listener: _resource_record_pre_action 22:10:16 INFO - Running main action method: read_buildbot_config 22:10:16 INFO - Using buildbot properties: 22:10:16 INFO - { 22:10:16 INFO - "project": "", 22:10:16 INFO - "product": "firefox", 22:10:16 INFO - "script_repo_revision": "production", 22:10:16 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:10:16 INFO - "repository": "", 22:10:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:10:16 INFO - "buildid": "20160524193102", 22:10:16 INFO - "pgo_build": "True", 22:10:16 INFO - "basedir": "/builds/slave/test", 22:10:16 INFO - "buildnumber": 980, 22:10:16 INFO - "slavename": "talos-linux64-ix-064", 22:10:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:10:16 INFO - "platform": "ubuntu64_hw", 22:10:16 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:10:16 INFO - "branch": "mozilla-inbound", 22:10:16 INFO - "repo_path": "integration/mozilla-inbound", 22:10:16 INFO - "revision": "ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec", 22:10:16 INFO - "stage_platform": "linux64-pgo", 22:10:16 INFO - "builduid": "fd7b3a4e974347269d7a5ee2113ce351", 22:10:16 INFO - "slavebuilddir": "test" 22:10:16 INFO - } 22:10:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:10:16 INFO - Running post-action listener: _resource_record_post_action 22:10:16 INFO - ##### 22:10:16 INFO - ##### Finished read-buildbot-config step (success) 22:10:16 INFO - ##### 22:10:16 INFO - ##### 22:10:16 INFO - ##### Running download-and-extract step. 22:10:16 INFO - ##### 22:10:16 INFO - Running pre-action listener: _resource_record_pre_action 22:10:16 INFO - Running main action method: download_and_extract 22:10:16 INFO - mkdir: /builds/slave/test/build/tests 22:10: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')]} 22:10:16 INFO - https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:10:22 INFO - Downloaded 1361 bytes. 22:10:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:22 INFO - Using the following test package requirements: 22:10:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:10:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:10:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'jsshell-linux-x86_64.zip'], 22:10:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:10:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:10:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:10:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:10:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:10:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:22 INFO - https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:25 INFO - Downloaded 24420905 bytes. 22:10:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:26 INFO - Return code: 0 22:10:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:26 INFO - https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:27 INFO - Downloaded 24420905 bytes. 22:10:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:29 INFO - Return code: 0 22:10: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')]} 22:10:29 INFO - https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:10:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:10:30 INFO - Downloaded 10834034 bytes. 22:10:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:10:30 INFO - Return code: 0 22:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:30 INFO - https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:10:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:10:31 INFO - Downloaded 58661332 bytes. 22:10:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:31 INFO - mkdir: /builds/slave/test/properties 22:10:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:10:31 INFO - Writing to file /builds/slave/test/properties/build_url 22:10:31 INFO - Contents: 22:10:31 INFO - build_url:https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:32 INFO - Running post-action listener: _resource_record_post_action 22:10:32 INFO - Running post-action listener: set_extra_try_arguments 22:10:32 INFO - ##### 22:10:32 INFO - ##### Finished download-and-extract step (success) 22:10:32 INFO - ##### 22:10:32 INFO - ##### 22:10:32 INFO - ##### Running populate-webroot step. 22:10:32 INFO - ##### 22:10:32 INFO - Running pre-action listener: _resource_record_pre_action 22:10:32 INFO - Running main action method: populate_webroot 22:10:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:10:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:10:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:10:32 INFO - '--tppagecycles', 22:10:32 INFO - '7'], 22:10:32 INFO - 'tests': ['tsvgm']}}, 22:10:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:10:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:10:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:10:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:10:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['damp', 'tps']}, 22:10:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'tests': ['damp', 'tps']}, 22:10:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['dromaeo_dom']}, 22:10:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:10:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'other-e10s': {'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['a11yr', 22:10:32 INFO - 'ts_paint', 22:10:32 INFO - 'tpaint', 22:10:32 INFO - 'sessionrestore', 22:10:32 INFO - 'sessionrestore_no_auto_restore', 22:10:32 INFO - 'tabpaint']}, 22:10:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['tsvgx', 22:10:32 INFO - 'tsvgr_opacity', 22:10:32 INFO - 'tart', 22:10:32 INFO - 'tscrollx', 22:10:32 INFO - 'cart']}, 22:10:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:10:32 INFO - 'tsvgr_opacity', 22:10:32 INFO - 'tart', 22:10:32 INFO - 'tscrollx', 22:10:32 INFO - 'cart']}, 22:10:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'talos_options': ['--disable-e10s'], 22:10:32 INFO - 'tests': ['tp5o']}, 22:10:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'tests': ['tp5o']}, 22:10:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'talos_options': ['--disable-e10s', 22:10:32 INFO - '--xperf_path', 22:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:32 INFO - 'tests': ['tp5n']}, 22:10:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:32 INFO - 'talos_options': ['--xperf_path', 22:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:32 INFO - 'tests': ['tp5n']}}, 22:10:32 INFO - 'talos.zip': {'path': '', 22:10:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:10:32 INFO - Running post-action listener: _resource_record_post_action 22:10:32 INFO - ##### 22:10:32 INFO - ##### Finished populate-webroot step (success) 22:10:32 INFO - ##### 22:10:32 INFO - ##### 22:10:32 INFO - ##### Running create-virtualenv step. 22:10:32 INFO - ##### 22:10:32 INFO - Running pre-action listener: _resource_record_pre_action 22:10:32 INFO - Running main action method: create_virtualenv 22:10:32 INFO - Creating virtualenv /builds/slave/test/build/venv 22:10:32 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 22:10:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:10:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:10:32 INFO - Using real prefix '/usr' 22:10:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:10:33 INFO - Installing distribute.............................................................................................................................................................................................done. 22:10:35 INFO - Installing pip.................done. 22:10:35 INFO - Return code: 0 22:10:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:35 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:35 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 22:10:35 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 22:10:35 INFO - Using env: {'DISPLAY': ':0', 22:10:35 INFO - 'HOME': '/home/cltbld', 22:10:35 INFO - 'LANG': 'en_US.UTF-8', 22:10:35 INFO - 'LANGUAGE': 'en_US:en', 22:10:35 INFO - 'LOGNAME': 'cltbld', 22:10:35 INFO - 'MAIL': '/var/mail/cltbld', 22:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:35 INFO - 'MOZ_NO_REMOTE': '1', 22:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:35 INFO - 'NO_EM_RESTART': '1', 22:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:35 INFO - 'PWD': '/builds/slave/test', 22:10:35 INFO - 'SHELL': '/bin/bash', 22:10:35 INFO - 'SHLVL': '1', 22:10:35 INFO - 'TERM': 'linux', 22:10:35 INFO - 'TMOUT': '86400', 22:10:35 INFO - 'USER': 'cltbld', 22:10:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:35 INFO - '_': '/tools/buildbot/bin/python'} 22:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:35 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 22:10:35 INFO - Cleaning up... 22:10:35 INFO - Return code: 0 22:10:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:35 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:35 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 22:10:35 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 22:10:35 INFO - Using env: {'DISPLAY': ':0', 22:10:35 INFO - 'HOME': '/home/cltbld', 22:10:35 INFO - 'LANG': 'en_US.UTF-8', 22:10:35 INFO - 'LANGUAGE': 'en_US:en', 22:10:35 INFO - 'LOGNAME': 'cltbld', 22:10:35 INFO - 'MAIL': '/var/mail/cltbld', 22:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:35 INFO - 'MOZ_NO_REMOTE': '1', 22:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:35 INFO - 'NO_EM_RESTART': '1', 22:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:35 INFO - 'PWD': '/builds/slave/test', 22:10:35 INFO - 'SHELL': '/bin/bash', 22:10:35 INFO - 'SHLVL': '1', 22:10:35 INFO - 'TERM': 'linux', 22:10:35 INFO - 'TMOUT': '86400', 22:10:35 INFO - 'USER': 'cltbld', 22:10:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:35 INFO - '_': '/tools/buildbot/bin/python'} 22:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:35 INFO - Downloading/unpacking psutil>=3.1.1 22:10:35 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 22:10:35 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 22:10:35 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 22:10:35 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 22:10:35 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 22:10:35 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 22:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:10:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:37 INFO - Installing collected packages: psutil 22:10:37 INFO - Running setup.py install for psutil 22:10:37 INFO - building 'psutil._psutil_linux' extension 22:10:38 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 22:10:38 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 22:10:38 INFO - building 'psutil._psutil_posix' extension 22:10:38 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 22:10:38 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 22:10:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:38 INFO - Successfully installed psutil 22:10:38 INFO - Cleaning up... 22:10:38 INFO - Return code: 0 22:10:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:10:38 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')]} 22:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:38 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')]} 22:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:38 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:38 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 22:10:38 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 22:10:38 INFO - Using env: {'DISPLAY': ':0', 22:10:38 INFO - 'HOME': '/home/cltbld', 22:10:38 INFO - 'LANG': 'en_US.UTF-8', 22:10:38 INFO - 'LANGUAGE': 'en_US:en', 22:10:38 INFO - 'LOGNAME': 'cltbld', 22:10:38 INFO - 'MAIL': '/var/mail/cltbld', 22:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:38 INFO - 'MOZ_NO_REMOTE': '1', 22:10:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:38 INFO - 'NO_EM_RESTART': '1', 22:10:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:38 INFO - 'PWD': '/builds/slave/test', 22:10:38 INFO - 'SHELL': '/bin/bash', 22:10:38 INFO - 'SHLVL': '1', 22:10:38 INFO - 'TERM': 'linux', 22:10:38 INFO - 'TMOUT': '86400', 22:10:38 INFO - 'USER': 'cltbld', 22:10:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:38 INFO - '_': '/tools/buildbot/bin/python'} 22:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:10:38 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 22:10:38 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 22:10:38 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 22:10:38 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 22:10:38 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 22:10:38 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 22:10:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:10:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:10:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:10:40 INFO - Installing collected packages: mozsystemmonitor 22:10:40 INFO - Running setup.py install for mozsystemmonitor 22:10:41 INFO - Successfully installed mozsystemmonitor 22:10:41 INFO - Cleaning up... 22:10:41 INFO - Return code: 0 22:10:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:41 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 22:10:41 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 22:10:41 INFO - Using env: {'DISPLAY': ':0', 22:10:41 INFO - 'HOME': '/home/cltbld', 22:10:41 INFO - 'LANG': 'en_US.UTF-8', 22:10:41 INFO - 'LANGUAGE': 'en_US:en', 22:10:41 INFO - 'LOGNAME': 'cltbld', 22:10:41 INFO - 'MAIL': '/var/mail/cltbld', 22:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:41 INFO - 'MOZ_NO_REMOTE': '1', 22:10:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:41 INFO - 'NO_EM_RESTART': '1', 22:10:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:41 INFO - 'PWD': '/builds/slave/test', 22:10:41 INFO - 'SHELL': '/bin/bash', 22:10:41 INFO - 'SHLVL': '1', 22:10:41 INFO - 'TERM': 'linux', 22:10:41 INFO - 'TMOUT': '86400', 22:10:41 INFO - 'USER': 'cltbld', 22:10:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:41 INFO - '_': '/tools/buildbot/bin/python'} 22:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:41 INFO - Downloading/unpacking blobuploader==1.2.4 22:10:41 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 22:10:41 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 22:10:41 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 22:10:41 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 22:10:41 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 22:10:41 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 22:10:43 INFO - Downloading blobuploader-1.2.4.tar.gz 22:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:10:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:10:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:43 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 22:10:44 INFO - Downloading docopt-0.6.1.tar.gz 22:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:10:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:10:44 INFO - Running setup.py install for blobuploader 22:10:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:10:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:10:44 INFO - Running setup.py install for requests 22:10:44 INFO - Running setup.py install for docopt 22:10:44 INFO - Successfully installed blobuploader requests docopt 22:10:44 INFO - Cleaning up... 22:10:44 INFO - Return code: 0 22:10:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:44 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:44 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 22:10:44 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 22:10:44 INFO - Using env: {'DISPLAY': ':0', 22:10:44 INFO - 'HOME': '/home/cltbld', 22:10:44 INFO - 'LANG': 'en_US.UTF-8', 22:10:44 INFO - 'LANGUAGE': 'en_US:en', 22:10:44 INFO - 'LOGNAME': 'cltbld', 22:10:44 INFO - 'MAIL': '/var/mail/cltbld', 22:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:44 INFO - 'MOZ_NO_REMOTE': '1', 22:10:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:44 INFO - 'NO_EM_RESTART': '1', 22:10:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:44 INFO - 'PWD': '/builds/slave/test', 22:10:44 INFO - 'SHELL': '/bin/bash', 22:10:44 INFO - 'SHLVL': '1', 22:10:44 INFO - 'TERM': 'linux', 22:10:44 INFO - 'TMOUT': '86400', 22:10:44 INFO - 'USER': 'cltbld', 22:10:44 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:44 INFO - '_': '/tools/buildbot/bin/python'} 22:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:45 INFO - Running setup.py (path:/tmp/pip-fEN8M2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:45 INFO - Running setup.py (path:/tmp/pip-pzvuRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:45 INFO - Running setup.py (path:/tmp/pip-v0XvH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:45 INFO - Running setup.py (path:/tmp/pip-ALlFtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:45 INFO - Running setup.py (path:/tmp/pip-BSOTL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:45 INFO - Running setup.py (path:/tmp/pip-TNBjZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:45 INFO - Running setup.py (path:/tmp/pip-DcovZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:45 INFO - Running setup.py (path:/tmp/pip-taPS3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:45 INFO - Running setup.py (path:/tmp/pip-W3Kv4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:45 INFO - Running setup.py (path:/tmp/pip-DuJvBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:45 INFO - Running setup.py (path:/tmp/pip-tSdrii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:45 INFO - Running setup.py (path:/tmp/pip-sYMtYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:45 INFO - Running setup.py (path:/tmp/pip-5_ZAq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:45 INFO - Running setup.py (path:/tmp/pip-ATHOoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:46 INFO - Running setup.py (path:/tmp/pip-sAo5Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:46 INFO - Running setup.py (path:/tmp/pip-fCXFu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:46 INFO - Running setup.py (path:/tmp/pip-UGP_Ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:10:46 INFO - Running setup.py install for manifestparser 22:10:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:10:46 INFO - Running setup.py install for mozcrash 22:10:46 INFO - Running setup.py install for mozdebug 22:10:46 INFO - Running setup.py install for mozdevice 22:10:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:10:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:10:46 INFO - Running setup.py install for mozfile 22:10:46 INFO - Running setup.py install for mozhttpd 22:10:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:10:46 INFO - Running setup.py install for mozinfo 22:10:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:10:46 INFO - Running setup.py install for mozInstall 22:10:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Running setup.py install for mozleak 22:10:47 INFO - Running setup.py install for mozlog 22:10:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Running setup.py install for moznetwork 22:10:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Running setup.py install for mozprocess 22:10:47 INFO - Running setup.py install for mozprofile 22:10:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Running setup.py install for mozrunner 22:10:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:10:47 INFO - Running setup.py install for mozscreenshot 22:10:47 INFO - Running setup.py install for moztest 22:10:47 INFO - Running setup.py install for mozversion 22:10:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:10:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:10:48 INFO - Cleaning up... 22:10:48 INFO - Return code: 0 22:10:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:48 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')]} 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 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')]} 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:48 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 22:10:48 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 22:10:48 INFO - Using env: {'DISPLAY': ':0', 22:10:48 INFO - 'HOME': '/home/cltbld', 22:10:48 INFO - 'LANG': 'en_US.UTF-8', 22:10:48 INFO - 'LANGUAGE': 'en_US:en', 22:10:48 INFO - 'LOGNAME': 'cltbld', 22:10:48 INFO - 'MAIL': '/var/mail/cltbld', 22:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:48 INFO - 'MOZ_NO_REMOTE': '1', 22:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:48 INFO - 'NO_EM_RESTART': '1', 22:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:48 INFO - 'PWD': '/builds/slave/test', 22:10:48 INFO - 'SHELL': '/bin/bash', 22:10:48 INFO - 'SHLVL': '1', 22:10:48 INFO - 'TERM': 'linux', 22:10:48 INFO - 'TMOUT': '86400', 22:10:48 INFO - 'USER': 'cltbld', 22:10:48 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:48 INFO - '_': '/tools/buildbot/bin/python'} 22:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:48 INFO - Running setup.py (path:/tmp/pip-Yh4qhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:48 INFO - Running setup.py (path:/tmp/pip-Hd4tle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:48 INFO - Running setup.py (path:/tmp/pip-ZAN44i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:48 INFO - Running setup.py (path:/tmp/pip-u8dAtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:48 INFO - Running setup.py (path:/tmp/pip-SiNaC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:48 INFO - Running setup.py (path:/tmp/pip-nqLCAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:48 INFO - Running setup.py (path:/tmp/pip-DvNe5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:48 INFO - Running setup.py (path:/tmp/pip-SkwG9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:48 INFO - Running setup.py (path:/tmp/pip-9r_Pfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:48 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)) 22:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:48 INFO - Running setup.py (path:/tmp/pip-FB1U4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:49 INFO - Running setup.py (path:/tmp/pip-MLpbu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:49 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:49 INFO - Running setup.py (path:/tmp/pip-EOwVEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:49 INFO - Running setup.py (path:/tmp/pip-ebixFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:49 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:49 INFO - Running setup.py (path:/tmp/pip-AUdWpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:49 INFO - Running setup.py (path:/tmp/pip-DUT8RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:49 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:49 INFO - Running setup.py (path:/tmp/pip-42WigA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:49 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)) 22:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:49 INFO - Running setup.py (path:/tmp/pip-ED413n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:49 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)) 22:10:49 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)) 22:10:49 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)) 22:10:49 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)) 22:10:49 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)) 22:10:49 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)) 22:10:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:51 INFO - Downloading blessings-1.6.tar.gz 22:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:51 INFO - Installing collected packages: blessings 22:10:51 INFO - Running setup.py install for blessings 22:10:51 INFO - Successfully installed blessings 22:10:51 INFO - Cleaning up... 22:10:51 INFO - Return code: 0 22:10:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:51 INFO - Reading from file tmpfile_stdout 22:10:51 INFO - Current package versions: 22:10:51 INFO - argparse == 1.2.1 22:10:51 INFO - blessings == 1.6 22:10:51 INFO - blobuploader == 1.2.4 22:10:51 INFO - docopt == 0.6.1 22:10:51 INFO - manifestparser == 1.1 22:10:51 INFO - mozInstall == 1.12 22:10:51 INFO - mozcrash == 0.17 22:10:51 INFO - mozdebug == 0.1 22:10:51 INFO - mozdevice == 0.48 22:10:51 INFO - mozfile == 1.2 22:10:51 INFO - mozhttpd == 0.7 22:10:51 INFO - mozinfo == 0.9 22:10:51 INFO - mozleak == 0.1 22:10:51 INFO - mozlog == 3.2 22:10:51 INFO - moznetwork == 0.27 22:10:51 INFO - mozprocess == 0.22 22:10:51 INFO - mozprofile == 0.28 22:10:51 INFO - mozrunner == 6.11 22:10:51 INFO - mozscreenshot == 0.1 22:10:51 INFO - mozsystemmonitor == 0.3 22:10:51 INFO - moztest == 0.7 22:10:51 INFO - mozversion == 1.4 22:10:51 INFO - psutil == 3.1.1 22:10:51 INFO - requests == 1.2.3 22:10:51 INFO - wsgiref == 0.1.2 22:10:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:51 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')]} 22:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:51 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')]} 22:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:51 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:51 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 22:10:51 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 22:10:51 INFO - Using env: {'DISPLAY': ':0', 22:10:51 INFO - 'HOME': '/home/cltbld', 22:10:51 INFO - 'LANG': 'en_US.UTF-8', 22:10:51 INFO - 'LANGUAGE': 'en_US:en', 22:10:51 INFO - 'LOGNAME': 'cltbld', 22:10:51 INFO - 'MAIL': '/var/mail/cltbld', 22:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:51 INFO - 'MOZ_NO_REMOTE': '1', 22:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:51 INFO - 'NO_EM_RESTART': '1', 22:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:51 INFO - 'PWD': '/builds/slave/test', 22:10:51 INFO - 'SHELL': '/bin/bash', 22:10:51 INFO - 'SHLVL': '1', 22:10:51 INFO - 'TERM': 'linux', 22:10:51 INFO - 'TMOUT': '86400', 22:10:51 INFO - 'USER': 'cltbld', 22:10:51 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:51 INFO - '_': '/tools/buildbot/bin/python'} 22:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 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)) 22:10:52 INFO - Cleaning up... 22:10:52 INFO - Return code: 0 22:10:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:52 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')]} 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:52 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')]} 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:52 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 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:52 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 22:10:52 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 22:10:52 INFO - Using env: {'DISPLAY': ':0', 22:10:52 INFO - 'HOME': '/home/cltbld', 22:10:52 INFO - 'LANG': 'en_US.UTF-8', 22:10:52 INFO - 'LANGUAGE': 'en_US:en', 22:10:52 INFO - 'LOGNAME': 'cltbld', 22:10:52 INFO - 'MAIL': '/var/mail/cltbld', 22:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:52 INFO - 'MOZ_NO_REMOTE': '1', 22:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:52 INFO - 'NO_EM_RESTART': '1', 22:10:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:52 INFO - 'PWD': '/builds/slave/test', 22:10:52 INFO - 'SHELL': '/bin/bash', 22:10:52 INFO - 'SHLVL': '1', 22:10:52 INFO - 'TERM': 'linux', 22:10:52 INFO - 'TMOUT': '86400', 22:10:52 INFO - 'USER': 'cltbld', 22:10:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:52 INFO - '_': '/tools/buildbot/bin/python'} 22:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:52 INFO - Downloading/unpacking jsonschema 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:54 INFO - Downloading/unpacking functools32 (from jsonschema) 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:54 INFO - warning: no files found matching '*.txt' 22:10:54 INFO - no previously-included directories found matching 'build' 22:10:54 INFO - no previously-included directories found matching 'dist' 22:10:54 INFO - no previously-included directories found matching '.git*' 22:10:54 INFO - Installing collected packages: jsonschema, functools32 22:10:54 INFO - Running setup.py install for functools32 22:10:54 INFO - warning: no files found matching '*.txt' 22:10:54 INFO - no previously-included directories found matching 'build' 22:10:54 INFO - no previously-included directories found matching 'dist' 22:10:54 INFO - no previously-included directories found matching '.git*' 22:10:54 INFO - Successfully installed jsonschema functools32 22:10:54 INFO - Cleaning up... 22:10:54 INFO - Return code: 0 22:10:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:10:54 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')]} 22:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:54 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')]} 22:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:54 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f666ae97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x202d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20f8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', '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 22:10:54 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', 'flake8'] in /builds/slave/test/build 22:10:54 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 flake8 22:10:54 INFO - Using env: {'DISPLAY': ':0', 22:10:54 INFO - 'HOME': '/home/cltbld', 22:10:54 INFO - 'LANG': 'en_US.UTF-8', 22:10:54 INFO - 'LANGUAGE': 'en_US:en', 22:10:54 INFO - 'LOGNAME': 'cltbld', 22:10:54 INFO - 'MAIL': '/var/mail/cltbld', 22:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:54 INFO - 'MOZ_NO_REMOTE': '1', 22:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:54 INFO - 'NO_EM_RESTART': '1', 22:10:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:54 INFO - 'PWD': '/builds/slave/test', 22:10:54 INFO - 'SHELL': '/bin/bash', 22:10:54 INFO - 'SHLVL': '1', 22:10:54 INFO - 'TERM': 'linux', 22:10:54 INFO - 'TMOUT': '86400', 22:10:54 INFO - 'USER': 'cltbld', 22:10:54 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:54 INFO - '_': '/tools/buildbot/bin/python'} 22:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:54 INFO - Downloading/unpacking flake8 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:54 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 22:10:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:10:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:10:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:56 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 22:10:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:10:57 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 22:10:57 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 22:10:57 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 22:10:57 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 22:10:57 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 22:10:57 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 22:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:10:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:57 INFO - no previously-included directories found matching 'docs/_build' 22:10:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:10:57 INFO - Running setup.py install for pep8 22:10:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:57 INFO - no previously-included directories found matching 'docs/_build' 22:10:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:10:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:10:57 INFO - Cleaning up... 22:10:57 INFO - Return code: 0 22:10:57 INFO - Running post-action listener: _resource_record_post_action 22:10:57 INFO - Running post-action listener: _start_resource_monitoring 22:10:57 INFO - Starting resource monitoring. 22:10:57 INFO - ##### 22:10:57 INFO - ##### Finished create-virtualenv step (success) 22:10:57 INFO - ##### 22:10:57 INFO - ##### 22:10:57 INFO - ##### Running install step. 22:10:57 INFO - ##### 22:10:57 INFO - Running pre-action listener: _resource_record_pre_action 22:10:57 INFO - Running main action method: install 22:10:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:57 INFO - Reading from file tmpfile_stdout 22:10:57 INFO - Detecting whether we're running mozinstall >=1.0... 22:10:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:10:58 INFO - Reading from file tmpfile_stdout 22:10:58 INFO - Output received: 22:10:58 INFO - Usage: mozinstall [options] installer 22:10:58 INFO - Options: 22:10:58 INFO - -h, --help show this help message and exit 22:10:58 INFO - -d DEST, --destination=DEST 22:10:58 INFO - Directory to install application into. [default: 22:10:58 INFO - "/builds/slave/test"] 22:10:58 INFO - --app=APP Application being installed. [default: firefox] 22:10:58 INFO - mkdir: /builds/slave/test/build/application 22:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:11:14 INFO - Reading from file tmpfile_stdout 22:11:14 INFO - Output received: 22:11:14 INFO - /builds/slave/test/build/application/firefox/firefox 22:11:14 INFO - Running post-action listener: _resource_record_post_action 22:11:14 INFO - ##### 22:11:14 INFO - ##### Finished install step (success) 22:11:14 INFO - ##### 22:11:14 INFO - ##### 22:11:14 INFO - ##### Running run-tests step. 22:11:14 INFO - ##### 22:11:14 INFO - Running pre-action listener: _resource_record_pre_action 22:11:14 INFO - Running main action method: run_tests 22:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:14 INFO - Python 2.7.3 22:11:14 INFO - Return code: 0 22:11:14 INFO - grabbing minidump binary from tooltool 22:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:14 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 0x21879c0>, '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 0x21888c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2188d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:14 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 22:11:14 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 22:11:14 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 22:11:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:14 INFO - Return code: 0 22:11:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:11:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:11:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:11:15 INFO - Return code: 0 22:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 22:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 22:11:15 INFO - Using env: {'DISPLAY': ':0', 22:11:15 INFO - 'HOME': '/home/cltbld', 22:11:15 INFO - 'LANG': 'en_US.UTF-8', 22:11:15 INFO - 'LANGUAGE': 'en_US:en', 22:11:15 INFO - 'LOGNAME': 'cltbld', 22:11:15 INFO - 'MAIL': '/var/mail/cltbld', 22:11:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:15 INFO - 'MOZ_NO_REMOTE': '1', 22:11:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:15 INFO - 'NO_EM_RESTART': '1', 22:11:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:15 INFO - 'PWD': '/builds/slave/test', 22:11:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:15 INFO - 'SHELL': '/bin/bash', 22:11:15 INFO - 'SHLVL': '1', 22:11:15 INFO - 'TERM': 'linux', 22:11:15 INFO - 'TMOUT': '86400', 22:11:15 INFO - 'USER': 'cltbld', 22:11:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100', 22:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:15 INFO - '_': '/tools/buildbot/bin/python'} 22:11:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 22:11:15 INFO - mozversion application_buildid: 20160524193102 22:11:15 INFO - mozversion application_changeset: ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec 22:11:15 INFO - mozversion application_display_name: Nightly 22:11:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:15 INFO - mozversion application_name: Firefox 22:11:15 INFO - mozversion application_remotingname: firefox 22:11:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:11:15 INFO - mozversion application_vendor: Mozilla 22:11:15 INFO - mozversion application_version: 49.0a1 22:11:15 INFO - mozversion platform_buildid: 20160524193102 22:11:15 INFO - mozversion platform_changeset: ba0982c4e30cfbc79ef1f4e25c4a1afb0766cfec 22:11:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:11:15 INFO - mozversion platform_version: 49.0a1 22:11:15 INFO - using testdate: 1464153075 22:11:15 INFO - actual date: 1464153075 22:11:15 INFO - starting webserver on 'localhost:52875' 22:11:15 INFO - SUITE-START | Running 2 tests 22:11:15 INFO - TEST-START | dromaeo_css 22:11:15 INFO - operating with platform_type : linux_ 22:11:15 INFO - Initialising browser for dromaeo_css test... 22:11:15 INFO - TEST-INFO | started process 10829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkVX1M4/profile http://localhost:52875/getInfo.html) 22:11:20 INFO - PROCESS | 10829 | __metrics Screen width/height:1600/1200 22:11:20 INFO - PROCESS | 10829 | colorDepth:24 22:11:20 INFO - PROCESS | 10829 | Browser inner width/height: 1024/697 22:11:20 INFO - PROCESS | 10829 | __metrics 22:11:21 ERROR - PROCESS | 10829 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:11:21 INFO - PROCESS | 10829 | console.error: PushService: 22:11:21 INFO - PROCESS | 10829 | stateChangeProcessEnqueue: Error transitioning state 22:11:21 INFO - PROCESS | 10829 | UnknownError 22:11:22 INFO - TEST-INFO | 10829: exit 0 22:11:22 INFO - Browser initialized. 22:11:22 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:22 INFO - TEST-INFO | started process 10912 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkVX1M4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:34 INFO - PROCESS | 10912 | RSS: Main: 155410432 22:11:34 INFO - PROCESS | 10912 | 22:12:24 INFO - PROCESS | 10912 | 1464153144935 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] 22:14:32 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52875/tests/dromaeo/cssquery-ext.html) 22:14:33 INFO - PROCESS | 10912 | RSS: Main: 253698048 22:14:33 INFO - PROCESS | 10912 | 22:17:36 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-ext.html (next: http://localhost:52875/tests/dromaeo/cssquery-jquery.html) 22:17:36 INFO - PROCESS | 10912 | RSS: Main: 238731264 22:17:36 INFO - PROCESS | 10912 | 22:20:38 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52875/tests/dromaeo/cssquery-mootools.html) 22:20:39 INFO - PROCESS | 10912 | RSS: Main: 248496128 22:20:39 INFO - PROCESS | 10912 | 22:23:41 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52875/tests/dromaeo/cssquery-prototype.html) 22:23:42 INFO - PROCESS | 10912 | RSS: Main: 237481984 22:23:42 INFO - PROCESS | 10912 | 22:26:44 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52875/tests/dromaeo/cssquery-yui.html) 22:26:45 INFO - PROCESS | 10912 | RSS: Main: 222736384 22:26:45 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | Cycle 1(1): loaded http://localhost:52875/tests/dromaeo/cssquery-yui.html (next: http://localhost:52875/tests/dromaeo/cssquery-dojo.html) 22:29:48 INFO - PROCESS | 10912 | RSS: Main: 266342400 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | __start_tp_report 22:29:48 INFO - PROCESS | 10912 | _x_x_mozilla_page_load 22:29:48 INFO - PROCESS | 10912 | _x_x_mozilla_page_load_details 22:29:48 INFO - PROCESS | 10912 | |i|pagename|runs| 22:29:48 INFO - PROCESS | 10912 | |0;dojo.html;3078.107348868828;3104.3170502489456;3106.922326941826;3139.984300078502;3154.0695494829024;5298.867528311785;5307.946920530799;5312.043832110218;5397.64915280507;5408.323959505062;5277.182036784295;5335.893282134355;5339.679619222849;5444.646098003627;5446.319210098738;4785.569298763102;4826.34844296019;5005.54950054495;5071.137906555894;5074.314967479399;4990.87522811929;5025.0703619830265;5032.320636714034;5055.191169412895;5055.862430953024;4755.548222918835;4888.70667759932;4961.131801934683;4966.304717339575;4968.080905032581;4960.404751429852;4964.726940018298;4964.925526117111;4973.1297023020825;5004.124278251292;4903.8528844134735;4928.531789479994;4941.431735350445;4968.87577810554;5002.499750025005;5222.2427747976335;5263.315768950012;5269.973650131763;5287.101192797234;5403.51368376844;5844.649321040752;5862.325832529271;5889.882202355929;5892.145638882381;5894.705264736746;5659.292588426447;5935.198748168985;5947.0484722444635;5949.137375080632;5950.047992321208;5356.892862142735;5389.434109418494;5406.91889621656;5474.890502189974;5492.697901615449;6029.186059881948;6036.15493830864;6036.426539478743;6038.033914573735;6041.335453100155;5598.888022239533;5648.406917273709;5782.335031471434;5785.710714464344;5787.623804452697;5768.307803063659;5858.033424484997;5865.94134058654;5869.882602348014;5873.442023007808;5645.125005624135;5695.40198279183;5702.429757024264;5704.372519022904;5706.229658996065;5594.272744543184;5650.152477128381;5708.4862362387585;5716.942830571724;5761.308642962871;4407.272799987966;4420.491643461043;4423.137488189836;4455.086707225011;4460.219461594273;3914.158455931988;3937.940930886039;3948.0919388540797;3967.821448034845;3972.7020473464604;5407.351117865803;5448.700321482356;5477.945220547823;5505.504504594605;5547.445255474501;5852.707364631751;5865.530757539472;5871.412858714094;5901.76392944286;5908.4091590841435;5561.777528898799;5570.275864137717;5571.13647384656;5576.386597474275;5580.553555715533;4882.633802464758;4993.7503124843615;5038;5058.140116180258;5063.139266324733;5921.733521991435;5926.555508336892;5943.5839491236065;5943.851403714855;5984.970075149597;5733.226014488074;5797.391273916178;5798.449147330997;5805.564582656233;5811.302643682616;5845.152452894392;5973.342932277359;6049.697515124139;6060.666663333559;6103.267607887082;5702.144678298288;5724.656520608777;5742.224799651994;5746.971265143815;5748.252727145445;5594.916076258778;5786.884262314814;5856.765729370777;5857.121431785266;5860;5610.887782244414;5667.659940403589;5687.488126068508;5690.374058853606;5695.943040569541;5679.6876171810945;5690.914636280377;5694.088945768657;5696.344920334214;5699.059655156852;5451.400345962021;5491.039068162976;5610.887782244251;5614.887702246014;5616.69108199053;5620.381758006534;5721.141828725725;5770.711464426846;5772.04761214395;5795.797147099831;5543.057680194457;5582.525485333792;5588.245586845724;5590.189422533791;5625.718714064362 22:29:48 INFO - PROCESS | 10912 | |1;ext.html;592.7214209321618;630.2405601250493;638.3138126513995;640.2221301119141;652.7193306878044;35772.46341304879;36490;42761;43754.7812260939;43762;45597.77201113998;46115.76942115285;46192.3071153933;46202.306965395546;46240;8807.119288071173;8993.325500587482;8997.100289971017;9007.864882026775;9007.95496022519;8827.940647122323;8983.416077954944;8988.146126118008;8988.415752976069;8991.28069754418;8994.280457563413;9034.232090272335;9059.048799876016;9064.592093355783;9065.546722663874;8975.95512022439;9023.278137748965;9036.50299233542;9038.774030649221;9046.276297896153;8741.213290803795;8837.60230789616;8843.0714774949;8843.955780221122;8847.203751662317;43104.275828966805;43408;43507.78246108781;43529.78235108836;43531;3527.400341941876;3603.549556305462;3717.8512859485586;3944.467496887912;4223.366495025771;32028.359432811212;32391.352172956646;32671.836640816644;32689.50965735516;33889.491657625156;4185.518665353493;4185.748855068675;4230.51349094855;4362.171187474401;4387.1445068211715;356087;359740;361741;361768;362412;59594.70202648959;60948.390516094274;61006.38993610007;61023.69488152619;61238.38761612327;63673.363266366745;63946;63999.360006400275;64063;64093.67953160204;16032;16400.589985250463;16406;16408.425705100504;16413.1793410329;12241.510339586495;12335.321557314255;12361.196522226026;12361.949234315183;12368.75262494763;16063.678726425405;18215;18342.724859127124;18418;18499;5650.802221922213;5658;5863.442972917566;5876;5984.790532331347;1022.3610243597751;1088.6298658456162;1105.1766434006556;1112.8497652816766;1118.7370967822555;31287.217819554236;32082.679173208435;32684;32940.941739824135;33329.83356081847;1073.8711354637494;1153.5385845661801;1166.1079274355125;1185.8754830742648;1221.267239656199;17093.658126837643;17148.914255428645;17256.309747610205;17291.91354043222;17304.826951730323;492781;502202;503198;505276;507029;58204.4179558199;58226.4177358221;58364.708176459695;58379.41620583825;58430.70784646049;1261.9684507887193;1279.1046267612521;1376.064276292131;1376.7865980772797;1392.1368751374214;29833.952912019828;33358.499622505195;33362;33729.83135084309;34039;51193;51504.48495514997;51555.48444515507;51648;51829.48170518397;401690;410808;412628;412644;414195;36739.44890826693;41211;41288.793556032026;41837.372439414044;42381.45711257211;47431.288530672755;47660.76169619268;47814.28278575894;48901;49215.507844922526;39718.60281397149;40073.79963100166;40794.796026020864;40937.38593921034;41183.382249265684;39537.60462395454;39824.60175398325;40872.79563602163;40919.590804091575;41215.79392103021;41169;41187.588124118374;41288.174236514504;42184.57815421806;42192;34725.82637086798;36483.45274820827;36958.815205924875;37312.253754924204;37565.62434375731;28198.71801281961;28328.651405574055;29931.55102673505;30830.691693083678;30928.845355773075 22:29:48 INFO - PROCESS | 10912 | |2;jquery.html;372.95710993235787;375.20456631940283;392.497603068073;397.42373558340404;401.1335515286981;14662.266886655678;14848;17283.913580432098;19019.144138513766;19067.33264335747;16225.513234602951;18878.244870205177;18882.905585472054;18946.24215031397;18946.810531894676;5481.780728770845;5656.151577263402;5685.971570142144;5698.202251684767;5698.629589076718;4095.262852686515;5594.384617692051;5957.255343082114;6206.317305096436;6222.253329600454;5952.375000624937;6165.383461653843;6262.780802671908;6283.5915665481825;6304.180456540644;6155.292141403706;6161.722722477477;6192.349803270682;6312.431881130698;6316.715747791361;4973.975130124362;5276.12943864262;5362.43694412085;5402.054640437907;5410.83767486976;12949.676258093528;13184.6703832404;13190.208587484685;13299;19159;2272.9090836366527;2277.81777457803;2461.913833015854;2738.9726102739114;2908.171171216193;9847.60609575616;9885.110340069356;10322.896771032269;11933.16467847251;14050.227237501933;1889.3859495663964;1899.943001709951;1979.8020197980231;2016.153215649416;2176.7823217678106;71048.6447567764;75241;85270.57364713198;86041.56979215126;87722.56138719329;9762.511874406393;9932.702018939442;9960.452175130438;10050.296479246528;10350.792984140275;13450;13499.325033748273;13503.189808611514;13655.658608534866;13736.244506552235;7539.924600754032;7613.314801667766;7695.576743279174;7708.768736937901;7791.259830316111;7587.924120758722;7715.498492598075;7755.883661745078;7806.55578717939;7808.726694565777;4171.79018084752;4229.13302772937;4281.400603915455;4281.785910704514;4660.930086048712;1779.0837718574944;1835.7521734565662;1868.2807119258912;1908.1985566062287;1936.1964784614509;885.9778505537284;975.8048390321964;984.2126298960802;1011.4891979550428;1043.6451606453843;18092.90953545224;18368.632627347644;19503.122359493856;19551.902240488707;20324.288649897182;981.1807141037172;993.2649839119;1037.1754455208127;1037.2376303416982;1066.99466502667;10492.895071049192;10693.411862347646;10881.510332035077;10971.177161712743;10977.231593788358;569627;590166;592690;596940;610946;71404.64297678582;73549.63225183947;96543;99953;102658;1026.9229807764298;1032.9070383665355;1043.2905624175633;1060.2684147937898;1061.1404762142688;16414.425495107615;17146.828531714524;17435.605151588097;17687.38094166698;17708.114594270493;38737.4189387165;39239.607603924735;41696.3745543811;42766;43116.568834311256;59265.40734592599;59610.70194648999;59998;60631.427888064914;61355.693221533606;8212.83574328522;8299.83400332002;8674.783130421538;8783.341249406192;8854.203121719076;13321.66695832612;13408.597742067754;13464.93267533656;13466.663333416744;13483.393247303698;5974.641521508724;9973.650922217688;10478.266521343432;11789.174757766874;11909.52361905514;10845.566177353134;11307.604233851776;11354;11689.123315751183;12012.879871201176;6254.655993920378;9186.356955013085;12057.698557535781;12427.316497592357;12469.316642253543;8278.420510564203;8381;8530.061693213866;8560.614772335135;8641.484690126503;9014.81970360576;11250.493727782106;12132.636020919386;12177;12185.878141218474 22:29:48 INFO - PROCESS | 10912 | |3;mootools.html;1342.738166057619;1468.2291796806671;1538.7614919687448;1638.3364737281397;1793.1572161084293;8615.095414981422;8918.821623567534;8929.598168082435;8936.59785309661;8939.793127927727;8758.255548278405;8951.955240223806;8954.597043133059;8969.372143949926;8977.551122443885;2044.090379780998;2296.1045192374986;2337.1352599538195;2350.6366307541584;2366.881655917206;2421.69728783902;2429.0283886445386;2433.513297340539;2433.9756602433927;2434.306222726523;2380.47629521505;2388.641703744435;2400.939976500593;2410.4576470294132;2414.4688168602966;1989.4031790462802;2262.151693115082;2303.8156947444163;2317.849339792925;2328.2898715891647;2326;2341.438054866837;2344.050659482912;2350.7061617297836;2352.3883790214495;8978.820423591556;9039.909600903973;9042.638294468214;9054.637814487412;9073.455592664395;5320.707361095138;5449.264349312872;5460.31746031746;5462.6449280797015;5463.043967305705;3210.2455922858107;3247.382997230519;3290.0623322353254;3344.4314466540745;3348.7990720556845;1041.6250149946013;1047.984280235797;1051.5478344311944;1056.4876035123036;1057.1806849691475;12645.93677031609;12725.30010849403;12732.299723515203;12745.61763147107;12752.234865908074;4807.18277892759;4808.687435316763;4851.539103785135;4862.95137048632;4920.581750551243;10456.424896630606;10542.578296868192;10560.47197640115;10566.894331056592;10571.577136914591;5434.809781657623;5449.536789372948;5451.37309209446;5454.645448045864;5456;4445.888852778707;4842.806287748521;4973.776180071905;4990.2764099205415;5091.516305950929;4021.755108040582;4024.396340548941;4044.797760111983;4112.712110152321;4226;1249.1824395504518;1266.778313795082;1294.1781968450102;1298.8571257161705;1318.6835159561635;959.1799011844772;966.4249771386014;967.7145242153582;970.6408628807245;978.1050338939932;5801.3328467225965;5829.8251052468495;5833;5913.142594323799;5941.910871336933;457.2067462951776;458.81188712627664;465.87188523155726;468.6250999200626;469.2023559948034;4048.2510735514024;4057.034390131463;4096.795160241976;4127.855525056609;4162.7502349859105;10809.40548269837;11271.549138034386;11287.153463490271;11295.661130166109;11299.039581635652;10866.782664346669;11284.379359135326;11299;11329.830052549218;11331.43342832855;8232.259096681328;8426.241638252586;8454.61954212062;8454.704085356983;8478.957605211934;5846.612062546537;6065.484433823174;6087.208662873798;6109.633421994695;6144.723487442986;10424;10531;10541.367517948947;10554.947225263824;10563.419011954416;10266.743331416774;10662.306950048222;10700.785984280426;10786.784264314512;10819.1344692426;7428.554286742813;7545.471816972759;7686.884696729665;7897.13131555517;8035.035795704553;8737.169968852948;8788.604512797066;8813.691520796741;8829.2495137914;8849.247813935888;7718.228177182462;8013.278804907586;8048.315893149148;8103.865458835649;8168.224018718212;7844.450888437755;7854.646540905787;7898.802529936797;7981.92018079835;8006.07930088024;6946.479014073863;7110.288971102848;7192.460565457507;7193.604351760494;7194.100737407824;4875.512448755097;4925.581325587222;4988.326575912205;5039.521245481674;5040.949590504195;5224.738763061908;5320.361556613231;5360.276362691143;5467.562594992471;5529.889402212014 22:29:48 INFO - PROCESS | 10912 | |4;prototype.html;320.2841648914675;324.14264271003196;329.3561088072817;331.5639933487465;333.082358103425;5309.150535914255;5376.8118115865955;5383.923215356929;5399.62202645815;5427.755750991205;5272.6836389816635;5274.657147285424;5295.311609490761;5299.470052994699;5308.601854860891;4029.8388064477385;4036.777977211252;4138.97930510347;4142.937855932163;4156.459660244179;4096.918061638766;4119.567445418233;4125.64931980782;4148.23257697327;4167.3332266837315;3929.5481019682816;4135.813888375031;4142.875713728593;4161.2301724181125;4174.290370636999;4050.7772072536;4140.612894680271;4144.461220041375;4160.791960401968;4188.643965262956;3634.3639863024127;3669.889903302905;3676.7460797194676;3685.1892583631825;3686.5760437549757;5108.208227724708;5136.306598609177;5149.356330458692;5162.948370516285;5169.198774190007;4515.9774201128785;4666.370040044584;4694.929576056362;4726.527347265281;4729.077829823186;5487.725613719338;5534.557235421156;5545.390007099216;5545.528630066449;5550.167474878801;3773.5471743390694;3932.7836968966694;3967.1867267210155;3974.205158968189;3981.7810020448865;5675.048974533261;5717.514011309044;5731.971340143273;5784.1902133701315;5795.1886735857015;4006.959930400717;4010.318245898204;4028.113814960704;4035.1727895781296;4058.553559108496;3873.6320049595242;4099.754014759124;4120.434234990685;4121.258173528734;4124.876253712393;4675.158471475167;4686.343911852344;4700.741459219707;4712.811487540528;4713.787879545429;5577.358603760538;5592.664440133605;5600.915986260209;5648.0231918828085;5654.40628733977;1346.6162143788972;1398.3847107272768;1408.084744915813;1413.1662319231702;1415.5541004583524;894.7226359828344;895.3329769321897;906.954652267384;912.9132732390412;914.4787471141518;5321.946780532223;5351.769093108607;5377.435369286247;5418.783248670088;5427.6472029317965;5721.256236689203;5737.282839645044;5741.25363702716;5755.510781583528;5799.318197722698;5185.637005409661;5425.37608175057;5435.619506634576;5475.780968761284;5533.142362933671;3022.7732920031085;3051.19143426992;3065.2490139915863;3065.4635438798123;3088.675689052662;5704.572157088235;5888.440598143169;5903.701185739216;5925.73334199962;5960.8807823844145;5780.710964451845;5854.560907931922;5873.4126587340925;5906.645601263938;5913.438223368773;5881.911771323434;5956.8384165088255;5969.880602388015;5989.640621562633;6005.849853753691;5443.537299329601;5724.8282551523525;5772;5785;5789;5664.150377443417;5679.829605111853;5696.458836410535;5708.62893911911;5758.042265629706;5762.740676669476;5967.806438712361;5972.850678733066;6004.969975150097;6024.969875150771;4027.859024934113;4065.556727361862;4080.7347522410946;4082.101937573848;4109.239790638741;5803.245578074823;5816.592838501433;5819.563532734977;5831.591788574759;5846.6784326862435;5419.214213939035;5581.888362232814;5604.495595396434;5678.971605141948;5684.516816070681;5557.805476808292;5568.359638641608;5577.888442231051;5594.4125866785025;5671.262735844314;5237;5297;5476.233327334096;5532.3637781655625;5540.22436858832;5562.638428502134;5577.888442231214;5624.29696287964;5647;5649;5487.506124448819;5534.501894829485;5536.446355364431;5537.5569954402745;5545.7781688732 22:29:48 INFO - PROCESS | 10912 | |5;yui.html;707.5471698113208;754.8980887580177;801.7246517912139;859.6002858670716;885.3581153663595;13317.134386264886;14046.227457489835;14057.437702491889;14078.014538982263;16186.433474828384;15730.764038539402;16306.83693163068;16319.83680163198;16342.509724708278;16360.345586176538;677.9220389655203;791.1692722641233;795.1174196641738;802.2859654907137;803.8673618852912;820.9466384684977;826.4215049465369;826.8304997475503;827.1025936858516;827.4456114403334;851.8764779107025;858.4205661178711;858.772425307291;859.2266959736224;859.9398042137053;686.4748467422401;816.9550674712948;825.7894236969535;854.0818619983512;855.5380094748859;622.3558616831602;623.1307326279814;623.1742940603718;623.1960770605913;623.5198896849446;13858.584242472742;13861.23763193024;13869.583912482642;13888.513902013483;13912.721745565133;13157;13417.865821341758;13453.86546134536;13469.797953030713;13504.93247533756;12303.69240768979;12529.686757831036;12531.18547294432;12604.621861344174;12645.49418023278;4334.978325108385;4373.518912919576;4424.9557504424865;4426.114777044571;4571.108633816407;59967;60242.69878650579;60540.697296513674;61211.38788612101;61527.38472615261;10650.201234907412;10684.679459616224;10918.727031824268;10988.615398461016;11139.275947063416;18808.341708040152;18843.81156188448;18975.810241897405;19006.90496547536;19046.238150473826;7995.880061799077;8207.507549547046;8221.958890205511;8265.545395003212;8320.750377488685;4572.51988541205;4658.091672123904;4762.833300834454;4775.283707443911;4901.50643086013;3248.2691394436533;3329.267561136546;3336.099253201622;3336.1659585103725;3336.6162891267813;632.3139393757824;646.3471893387618;648.0667838312814;651.7686221391433;653.7417720000668;641.2881701311541;709.3722055980392;719.9452801645614;732.9230430804795;762.3748526208456;9065.864012039825;9682.298045788293;9722.61109555624;9743.062488513975;9849.064338887794;557.9928229545628;581.776016233753;671.5735507952388;679.0052572980608;682.8361193313668;4645.094206629742;4665.486796452387;4718.174319494075;4754.95245047552;4775.140474714515;68545.31454685389;70323;70466;71010.64494677597;71338;60930;63051;63178.684106579174;63342.049869252;64076;56855.715721421955;57553;57629.13556296743;58036;58108.709456453296;8637.438566493158;8691.478511289342;8725.694600688945;8787.694029925418;8790.164934331227;37484.43773343456;38214.5762644601;38321.43479017957;39066.60933390629;39086.804565976985;46782.53217467782;46938.29592556046;47548;49930;52002.73998629983;12250.87749122533;12267.509299628271;12415.503379864704;12758.872411275768;12854.035947303802;13240.470381184645;13362.398692058687;13650.522231721841;13976.301184940916;14062.718745624825;13365.131266468037;13602.183868967893;13678.726425471235;14109.435622574982;14212.715745684822;13336.19982801035;13575.117617354841;13595;13961.232132232824;14232;13321.467141314239;13353.39909704085;13489.460421583419;13493.662658433617;13529.797053044016;10985.725356866142;11007;11160.55357785712;11243.66269011931;11382.14633902444;10689.625863094754;10852.348859068481;11049.39228342449;11321.830172547583;11502.367369794742 22:29:48 INFO - PROCESS | 10912 | __end_tp_report 22:29:48 INFO - PROCESS | 10912 | __start_cc_report 22:29:48 INFO - PROCESS | 10912 | _x_x_mozilla_cycle_collect,812 22:29:48 INFO - PROCESS | 10912 | __end_cc_report 22:29:48 INFO - PROCESS | 10912 | __startTimestamp1464154188386__endTimestamp 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | ------- Summary: start ------- 22:29:48 INFO - PROCESS | 10912 | Number of tests: 6 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#0] dojo.html Cycles:165 Average:5391.74 Median:5594.92 stddev:617.46 (11.0%) stddev-sans-first:592.08 22:29:48 INFO - PROCESS | 10912 | Values: 3078.1 3104.3 3106.9 3140.0 3154.1 5298.9 5307.9 5312.0 5397.6 5408.3 5277.2 5335.9 5339.7 5444.6 5446.3 4785.6 4826.3 5005.5 5071.1 5074.3 4990.9 5025.1 5032.3 5055.2 5055.9 4755.5 4888.7 4961.1 4966.3 4968.1 4960.4 4964.7 4964.9 4973.1 5004.1 4903.9 4928.5 4941.4 4968.9 5002.5 5222.2 5263.3 5270.0 5287.1 5403.5 5844.6 5862.3 5889.9 5892.1 5894.7 5659.3 5935.2 5947.0 5949.1 5950.0 5356.9 5389.4 5406.9 5474.9 5492.7 6029.2 6036.2 6036.4 6038.0 6041.3 5598.9 5648.4 5782.3 5785.7 5787.6 5768.3 5858.0 5865.9 5869.9 5873.4 5645.1 5695.4 5702.4 5704.4 5706.2 5594.3 5650.2 5708.5 5716.9 5761.3 4407.3 4420.5 4423.1 4455.1 4460.2 3914.2 3937.9 3948.1 3967.8 3972.7 5407.4 5448.7 5477.9 5505.5 5547.4 5852.7 5865.5 5871.4 5901.8 5908.4 5561.8 5570.3 5571.1 5576.4 5580.6 4882.6 4993.8 5038.0 5058.1 5063.1 5921.7 5926.6 5943.6 5943.9 5985.0 5733.2 5797.4 5798.4 5805.6 5811.3 5845.2 5973.3 6049.7 6060.7 6103.3 5702.1 5724.7 5742.2 5747.0 5748.3 5594.9 5786.9 5856.8 5857.1 5860.0 5610.9 5667.7 5687.5 5690.4 5695.9 5679.7 5690.9 5694.1 5696.3 5699.1 5451.4 5491.0 5610.9 5614.9 5616.7 5620.4 5721.1 5770.7 5772.0 5795.8 5543.1 5582.5 5588.2 5590.2 5625.7 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#1] ext.html Cycles:180 Average:59443.06 Median:227741.90 stddev:113189.01 (49.7%) stddev-sans-first:113420.29 22:29:48 INFO - PROCESS | 10912 | Values: 592.7 630.2 638.3 640.2 652.7 35772.5 36490.0 42761.0 43754.8 43762.0 45597.8 46115.8 46192.3 46202.3 46240.0 8807.1 8993.3 8997.1 9007.9 9008.0 8827.9 8983.4 8988.1 8988.4 8991.3 8994.3 9034.2 9059.0 9064.6 9065.5 8976.0 9023.3 9036.5 9038.8 9046.3 8741.2 8837.6 8843.1 8844.0 8847.2 43104.3 43408.0 43507.8 43529.8 43531.0 3527.4 3603.5 3717.9 3944.5 4223.4 32028.4 32391.4 32671.8 32689.5 33889.5 4185.5 4185.7 4230.5 4362.2 4387.1 356087.0 359740.0 361741.0 361768.0 362412.0 59594.7 60948.4 61006.4 61023.7 61238.4 63673.4 63946.0 63999.4 64063.0 64093.7 16032.0 16400.6 16406.0 16408.4 16413.2 12241.5 12335.3 12361.2 12361.9 12368.8 16063.7 18215.0 18342.7 18418.0 18499.0 5650.8 5658.0 5863.4 5876.0 5984.8 1022.4 1088.6 1105.2 1112.8 1118.7 31287.2 32082.7 32684.0 32940.9 33329.8 1073.9 1153.5 1166.1 1185.9 1221.3 17093.7 17148.9 17256.3 17291.9 17304.8 492781.0 502202.0 503198.0 505276.0 507029.0 58204.4 58226.4 58364.7 58379.4 58430.7 1262.0 1279.1 1376.1 1376.8 1392.1 29834.0 33358.5 33362.0 33729.8 34039.0 51193.0 51504.5 51555.5 51648.0 51829.5 401690.0 410808.0 412628.0 412644.0 414195.0 36739.4 41211.0 41288.8 41837.4 42381.5 47431.3 47660.8 47814.3 48901.0 49215.5 39718.6 40073.8 40794.8 40937.4 41183.4 39537.6 39824.6 40872.8 40919.6 41215.8 41169.0 41187.6 41288.2 42184.6 42192.0 34725.8 36483.5 36958.8 37312.3 37565.6 28198.7 28328.7 29931.6 30830.7 30928.8 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#2] jquery.html Cycles:180 Average:31313.39 Median:19818.52 stddev:97253.12 (490.7%) stddev-sans-first:97498.19 22:29:48 INFO - PROCESS | 10912 | Values: 373.0 375.2 392.5 397.4 401.1 14662.3 14848.0 17283.9 19019.1 19067.3 16225.5 18878.2 18882.9 18946.2 18946.8 5481.8 5656.2 5686.0 5698.2 5698.6 4095.3 5594.4 5957.3 6206.3 6222.3 5952.4 6165.4 6262.8 6283.6 6304.2 6155.3 6161.7 6192.3 6312.4 6316.7 4974.0 5276.1 5362.4 5402.1 5410.8 12949.7 13184.7 13190.2 13299.0 19159.0 2272.9 2277.8 2461.9 2739.0 2908.2 9847.6 9885.1 10322.9 11933.2 14050.2 1889.4 1899.9 1979.8 2016.2 2176.8 71048.6 75241.0 85270.6 86041.6 87722.6 9762.5 9932.7 9960.5 10050.3 10350.8 13450.0 13499.3 13503.2 13655.7 13736.2 7539.9 7613.3 7695.6 7708.8 7791.3 7587.9 7715.5 7755.9 7806.6 7808.7 4171.8 4229.1 4281.4 4281.8 4660.9 1779.1 1835.8 1868.3 1908.2 1936.2 886.0 975.8 984.2 1011.5 1043.6 18092.9 18368.6 19503.1 19551.9 20324.3 981.2 993.3 1037.2 1037.2 1067.0 10492.9 10693.4 10881.5 10971.2 10977.2 569627.0 590166.0 592690.0 596940.0 610946.0 71404.6 73549.6 96543.0 99953.0 102658.0 1026.9 1032.9 1043.3 1060.3 1061.1 16414.4 17146.8 17435.6 17687.4 17708.1 38737.4 39239.6 41696.4 42766.0 43116.6 59265.4 59610.7 59998.0 60631.4 61355.7 8212.8 8299.8 8674.8 8783.3 8854.2 13321.7 13408.6 13464.9 13466.7 13483.4 5974.6 9973.7 10478.3 11789.2 11909.5 10845.6 11307.6 11354.0 11689.1 12012.9 6254.7 9186.4 12057.7 12427.3 12469.3 8278.4 8381.0 8530.1 8560.6 8641.5 9014.8 11250.5 12132.6 12177.0 12185.9 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#3] mootools.html Cycles:180 Average:5917.16 Median:2638.19 stddev:3435.81 (130.2%) stddev-sans-first:3428.25 22:29:48 INFO - PROCESS | 10912 | Values: 1342.7 1468.2 1538.8 1638.3 1793.2 8615.1 8918.8 8929.6 8936.6 8939.8 8758.3 8952.0 8954.6 8969.4 8977.6 2044.1 2296.1 2337.1 2350.6 2366.9 2421.7 2429.0 2433.5 2434.0 2434.3 2380.5 2388.6 2400.9 2410.5 2414.5 1989.4 2262.2 2303.8 2317.8 2328.3 2326.0 2341.4 2344.1 2350.7 2352.4 8978.8 9039.9 9042.6 9054.6 9073.5 5320.7 5449.3 5460.3 5462.6 5463.0 3210.2 3247.4 3290.1 3344.4 3348.8 1041.6 1048.0 1051.5 1056.5 1057.2 12645.9 12725.3 12732.3 12745.6 12752.2 4807.2 4808.7 4851.5 4863.0 4920.6 10456.4 10542.6 10560.5 10566.9 10571.6 5434.8 5449.5 5451.4 5454.6 5456.0 4445.9 4842.8 4973.8 4990.3 5091.5 4021.8 4024.4 4044.8 4112.7 4226.0 1249.2 1266.8 1294.2 1298.9 1318.7 959.2 966.4 967.7 970.6 978.1 5801.3 5829.8 5833.0 5913.1 5941.9 457.2 458.8 465.9 468.6 469.2 4048.3 4057.0 4096.8 4127.9 4162.8 10809.4 11271.5 11287.2 11295.7 11299.0 10866.8 11284.4 11299.0 11329.8 11331.4 8232.3 8426.2 8454.6 8454.7 8479.0 5846.6 6065.5 6087.2 6109.6 6144.7 10424.0 10531.0 10541.4 10554.9 10563.4 10266.7 10662.3 10700.8 10786.8 10819.1 7428.6 7545.5 7686.9 7897.1 8035.0 8737.2 8788.6 8813.7 8829.2 8849.2 7718.2 8013.3 8048.3 8103.9 8168.2 7844.5 7854.6 7898.8 7981.9 8006.1 6946.5 7110.3 7192.5 7193.6 7194.1 4875.5 4925.6 4988.3 5039.5 5040.9 5224.7 5320.4 5360.3 5467.6 5529.9 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#4] prototype.html Cycles:180 Average:4698.96 Median:5419.00 stddev:1398.23 (25.8%) stddev-sans-first:1362.98 22:29:48 INFO - PROCESS | 10912 | Values: 320.3 324.1 329.4 331.6 333.1 5309.2 5376.8 5383.9 5399.6 5427.8 5272.7 5274.7 5295.3 5299.5 5308.6 4029.8 4036.8 4139.0 4142.9 4156.5 4096.9 4119.6 4125.6 4148.2 4167.3 3929.5 4135.8 4142.9 4161.2 4174.3 4050.8 4140.6 4144.5 4160.8 4188.6 3634.4 3669.9 3676.7 3685.2 3686.6 5108.2 5136.3 5149.4 5162.9 5169.2 4516.0 4666.4 4694.9 4726.5 4729.1 5487.7 5534.6 5545.4 5545.5 5550.2 3773.5 3932.8 3967.2 3974.2 3981.8 5675.0 5717.5 5732.0 5784.2 5795.2 4007.0 4010.3 4028.1 4035.2 4058.6 3873.6 4099.8 4120.4 4121.3 4124.9 4675.2 4686.3 4700.7 4712.8 4713.8 5577.4 5592.7 5600.9 5648.0 5654.4 1346.6 1398.4 1408.1 1413.2 1415.6 894.7 895.3 907.0 912.9 914.5 5321.9 5351.8 5377.4 5418.8 5427.6 5721.3 5737.3 5741.3 5755.5 5799.3 5185.6 5425.4 5435.6 5475.8 5533.1 3022.8 3051.2 3065.2 3065.5 3088.7 5704.6 5888.4 5903.7 5925.7 5960.9 5780.7 5854.6 5873.4 5906.6 5913.4 5881.9 5956.8 5969.9 5989.6 6005.8 5443.5 5724.8 5772.0 5785.0 5789.0 5664.2 5679.8 5696.5 5708.6 5758.0 5762.7 5967.8 5972.9 6005.0 6025.0 4027.9 4065.6 4080.7 4082.1 4109.2 5803.2 5816.6 5819.6 5831.6 5846.7 5419.2 5581.9 5604.5 5679.0 5684.5 5557.8 5568.4 5577.9 5594.4 5671.3 5237.0 5297.0 5476.2 5532.4 5540.2 5562.6 5577.9 5624.3 5647.0 5649.0 5487.5 5534.5 5536.4 5537.6 5545.8 22:29:48 INFO - PROCESS | 10912 | 22:29:48 INFO - PROCESS | 10912 | [#5] yui.html Cycles:180 Average:16072.72 Median:25828.24 stddev:19349.74 (74.9%) stddev-sans-first:19369.61 22:29:48 INFO - PROCESS | 10912 | Values: 707.5 754.9 801.7 859.6 885.4 13317.1 14046.2 14057.4 14078.0 16186.4 15730.8 16306.8 16319.8 16342.5 16360.3 677.9 791.2 795.1 802.3 803.9 820.9 826.4 826.8 827.1 827.4 851.9 858.4 858.8 859.2 859.9 686.5 817.0 825.8 854.1 855.5 622.4 623.1 623.2 623.2 623.5 13858.6 13861.2 13869.6 13888.5 13912.7 13157.0 13417.9 13453.9 13469.8 13504.9 12303.7 12529.7 12531.2 12604.6 12645.5 4335.0 4373.5 4425.0 4426.1 4571.1 59967.0 60242.7 60540.7 61211.4 61527.4 10650.2 10684.7 10918.7 10988.6 11139.3 18808.3 18843.8 18975.8 19006.9 19046.2 7995.9 8207.5 8222.0 8265.5 8320.8 4572.5 4658.1 4762.8 4775.3 4901.5 3248.3 3329.3 3336.1 3336.2 3336.6 632.3 646.3 648.1 651.8 653.7 641.3 709.4 719.9 732.9 762.4 9065.9 9682.3 9722.6 9743.1 9849.1 558.0 581.8 671.6 679.0 682.8 4645.1 4665.5 4718.2 4755.0 4775.1 68545.3 70323.0 70466.0 71010.6 71338.0 60930.0 63051.0 63178.7 63342.0 64076.0 56855.7 57553.0 57629.1 58036.0 58108.7 8637.4 8691.5 8725.7 8787.7 8790.2 37484.4 38214.6 38321.4 39066.6 39086.8 46782.5 46938.3 47548.0 49930.0 52002.7 12250.9 12267.5 12415.5 12758.9 12854.0 13240.5 13362.4 13650.5 13976.3 14062.7 13365.1 13602.2 13678.7 14109.4 14212.7 13336.2 13575.1 13595.0 13961.2 14232.0 13321.5 13353.4 13489.5 13493.7 13529.8 10985.7 11007.0 11160.6 11243.7 11382.1 10689.6 10852.3 11049.4 11321.8 11502.4 22:29:48 INFO - PROCESS | 10912 | -------- Summary: end -------- 22:29:48 INFO - PROCESS | 10912 | 22:29:49 INFO - TEST-INFO | 10912: exit 0 22:29:49 INFO - TEST-OK | dromaeo_css | took 1113196ms 22:29:49 INFO - TEST-START | kraken 22:29:49 INFO - operating with platform_type : linux_ 22:29:49 INFO - Initialising browser for kraken test... 22:29:49 INFO - TEST-INFO | started process 11097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5y2ZdP/profile http://localhost:52875/getInfo.html) 22:29:53 INFO - PROCESS | 11097 | __metrics Screen width/height:1600/1200 22:29:53 INFO - PROCESS | 11097 | colorDepth:24 22:29:53 INFO - PROCESS | 11097 | Browser inner width/height: 1024/697 22:29:53 INFO - PROCESS | 11097 | __metrics 22:29:54 ERROR - PROCESS | 11097 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:29:55 INFO - TEST-INFO | 11097: exit 0 22:29:55 INFO - Browser initialized. 22:29:55 INFO - Running cycle 1/1 for kraken test... 22:29:55 INFO - TEST-INFO | started process 11179 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5y2ZdP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:07 INFO - PROCESS | 11179 | RSS: Main: 154841088 22:30:07 INFO - PROCESS | 11179 | 22:30:09 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52875/tests/kraken/driver.html?testName=audio-beat-detection) 22:30:10 INFO - PROCESS | 11179 | RSS: Main: 211394560 22:30:10 INFO - PROCESS | 11179 | 22:30:13 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52875/tests/kraken/driver.html?testName=audio-dft) 22:30:13 INFO - PROCESS | 11179 | RSS: Main: 199680000 22:30:13 INFO - PROCESS | 11179 | 22:30:17 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52875/tests/kraken/driver.html?testName=audio-fft) 22:30:17 INFO - PROCESS | 11179 | RSS: Main: 267984896 22:30:17 INFO - PROCESS | 11179 | 22:30:19 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52875/tests/kraken/driver.html?testName=audio-oscillator) 22:30:20 INFO - PROCESS | 11179 | RSS: Main: 176562176 22:30:20 INFO - PROCESS | 11179 | 22:30:22 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52875/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:23 INFO - PROCESS | 11179 | RSS: Main: 181243904 22:30:23 INFO - PROCESS | 11179 | 22:30:26 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52875/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:26 INFO - PROCESS | 11179 | RSS: Main: 217145344 22:30:26 INFO - PROCESS | 11179 | 22:30:29 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52875/tests/kraken/driver.html?testName=imaging-desaturate) 22:30:30 INFO - PROCESS | 11179 | RSS: Main: 218075136 22:30:30 INFO - PROCESS | 11179 | 22:30:33 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52875/tests/kraken/driver.html?testName=json-parse-financial) 22:30:33 INFO - PROCESS | 11179 | RSS: Main: 218550272 22:30:33 INFO - PROCESS | 11179 | 22:30:35 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52875/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:36 INFO - PROCESS | 11179 | RSS: Main: 204013568 22:30:36 INFO - PROCESS | 11179 | 22:30:37 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:38 INFO - PROCESS | 11179 | RSS: Main: 206426112 22:30:38 INFO - PROCESS | 11179 | 22:30:40 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:41 INFO - PROCESS | 11179 | RSS: Main: 218021888 22:30:41 INFO - PROCESS | 11179 | 22:30:43 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:44 INFO - PROCESS | 11179 | RSS: Main: 226299904 22:30:44 INFO - PROCESS | 11179 | 22:30:47 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:47 INFO - PROCESS | 11179 | RSS: Main: 222576640 22:30:47 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | Cycle 1(1): loaded http://localhost:52875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52875/tests/kraken/driver.html?testName=ai-astar) 22:30:49 INFO - PROCESS | 11179 | RSS: Main: 268492800 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | __start_tp_report 22:30:49 INFO - PROCESS | 11179 | _x_x_mozilla_page_load 22:30:49 INFO - PROCESS | 11179 | _x_x_mozilla_page_load_details 22:30:49 INFO - PROCESS | 11179 | |i|pagename|runs| 22:30:49 INFO - PROCESS | 11179 | |0;ai-astar;111;104;110;119;110;111;110;109;111;110 22:30:49 INFO - PROCESS | 11179 | |1;audio-beat-detection;149;123;126;181;123;127;155;125;172;134 22:30:49 INFO - PROCESS | 11179 | |2;audio-dft;160;161;161;160;162;161;162;160;162;161 22:30:49 INFO - PROCESS | 11179 | |3;audio-fft;98;75;75;110;74;75;74;74;97;74 22:30:49 INFO - PROCESS | 11179 | |4;audio-oscillator;83;83;121;84;82;83;118;82;83;127 22:30:49 INFO - PROCESS | 11179 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 22:30:49 INFO - PROCESS | 11179 | |6;imaging-darkroom;145;145;146;145;145;146;145;145;145;145 22:30:49 INFO - PROCESS | 11179 | |7;imaging-desaturate;95;96;95;94;95;95;95;94;95;95 22:30:49 INFO - PROCESS | 11179 | |8;json-parse-financial;54;54;53;53;55;53;53;70;53;51 22:30:49 INFO - PROCESS | 11179 | |9;json-stringify-tinderbox;61;61;61;62;62;61;62;62;61;61 22:30:49 INFO - PROCESS | 11179 | |10;stanford-crypto-aes;79;79;84;80;80;83;80;79;84;80 22:30:49 INFO - PROCESS | 11179 | |11;stanford-crypto-ccm;120;129;123;121;118;118;128;126;170;125 22:30:49 INFO - PROCESS | 11179 | |12;stanford-crypto-pbkdf2;158;159;163;160;160;162;162;167;160;159 22:30:49 INFO - PROCESS | 11179 | |13;stanford-crypto-sha256-iterative;62;63;63;62;62;66;62;65;62;62 22:30:49 INFO - PROCESS | 11179 | __end_tp_report 22:30:49 INFO - PROCESS | 11179 | __start_cc_report 22:30:49 INFO - PROCESS | 11179 | _x_x_mozilla_cycle_collect,1657 22:30:49 INFO - PROCESS | 11179 | __end_cc_report 22:30:49 INFO - PROCESS | 11179 | __startTimestamp1464154249563__endTimestamp 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | ------- Summary: start ------- 22:30:49 INFO - PROCESS | 11179 | Number of tests: 14 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.50 stddev:3.63 (3.3%) stddev-sans-first:3.84 22:30:49 INFO - PROCESS | 11179 | Values: 111.0 104.0 110.0 119.0 110.0 111.0 110.0 109.0 111.0 110.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:141.50 stddev:21.58 (15.3%) stddev-sans-first:22.72 22:30:49 INFO - PROCESS | 11179 | Values: 149.0 123.0 126.0 181.0 123.0 127.0 155.0 125.0 172.0 134.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 22:30:49 INFO - PROCESS | 11179 | Values: 160.0 161.0 161.0 160.0 162.0 161.0 162.0 160.0 162.0 161.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:13.60 (18.1%) stddev-sans-first:13.23 22:30:49 INFO - PROCESS | 11179 | Values: 98.0 75.0 75.0 110.0 74.0 75.0 74.0 74.0 97.0 74.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:83.00 stddev:19.04 (22.9%) stddev-sans-first:19.73 22:30:49 INFO - PROCESS | 11179 | Values: 83.0 83.0 121.0 84.0 82.0 83.0 118.0 82.0 83.0 127.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:30:49 INFO - PROCESS | 11179 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:30:49 INFO - PROCESS | 11179 | Values: 145.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:30:49 INFO - PROCESS | 11179 | Values: 95.0 96.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:53.50 stddev:5.40 (10.1%) stddev-sans-first:5.72 22:30:49 INFO - PROCESS | 11179 | Values: 54.0 54.0 53.0 53.0 55.0 53.0 53.0 70.0 53.0 51.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 22:30:49 INFO - PROCESS | 11179 | Values: 61.0 61.0 61.0 62.0 62.0 61.0 62.0 62.0 61.0 61.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.00 stddev:2.04 (2.6%) stddev-sans-first:2.06 22:30:49 INFO - PROCESS | 11179 | Values: 79.0 79.0 84.0 80.0 80.0 83.0 80.0 79.0 84.0 80.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:125.50 stddev:15.33 (12.2%) stddev-sans-first:16.00 22:30:49 INFO - PROCESS | 11179 | Values: 120.0 129.0 123.0 121.0 118.0 118.0 128.0 126.0 170.0 125.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.00 Median:161.00 stddev:2.62 (1.6%) stddev-sans-first:2.55 22:30:49 INFO - PROCESS | 11179 | Values: 158.0 159.0 163.0 160.0 160.0 162.0 162.0 167.0 160.0 159.0 22:30:49 INFO - PROCESS | 11179 | 22:30:49 INFO - PROCESS | 11179 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:62.50 stddev:1.45 (2.3%) stddev-sans-first:1.50 22:30:49 INFO - PROCESS | 11179 | Values: 62.0 63.0 63.0 62.0 62.0 66.0 62.0 65.0 62.0 62.0 22:30:49 INFO - PROCESS | 11179 | -------- Summary: end -------- 22:30:49 INFO - PROCESS | 11179 | 22:30:50 INFO - TEST-INFO | 11179: exit 0 22:30:50 INFO - TEST-OK | kraken | took 61461ms 22:30:50 INFO - SUITE-END | took 1174s 22:30:50 INFO - Completed test suite (00:19:34) 22:30:50 INFO - javascript benchmark 22:30:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.107348868828, 3104.3170502489456, 3106.922326941826, 3139.984300078502, 3154.0695494829024, 5298.867528311785, 5307.946920530799, 5312.043832110218, 5397.64915280507, 5408.323959505062, 5277.182036784295, 5335.893282134355, 5339.679619222849, 5444.646098003627, 5446.319210098738, 4785.569298763102, 4826.34844296019, 5005.54950054495, 5071.137906555894, 5074.314967479399, 4990.87522811929, 5025.0703619830265, 5032.320636714034, 5055.191169412895, 5055.862430953024, 4755.548222918835, 4888.70667759932, 4961.131801934683, 4966.304717339575, 4968.080905032581, 4960.404751429852, 4964.726940018298, 4964.925526117111, 4973.1297023020825, 5004.124278251292, 4903.8528844134735, 4928.531789479994, 4941.431735350445, 4968.87577810554, 5002.499750025005, 5222.2427747976335, 5263.315768950012, 5269.973650131763, 5287.101192797234, 5403.51368376844, 5844.649321040752, 5862.325832529271, 5889.882202355929, 5892.145638882381, 5894.705264736746, 5659.292588426447, 5935.198748168985, 5947.0484722444635, 5949.137375080632, 5950.047992321208, 5356.892862142735, 5389.434109418494, 5406.91889621656, 5474.890502189974, 5492.697901615449, 6029.186059881948, 6036.15493830864, 6036.426539478743, 6038.033914573735, 6041.335453100155, 5598.888022239533, 5648.406917273709, 5782.335031471434, 5785.710714464344, 5787.623804452697, 5768.307803063659, 5858.033424484997, 5865.94134058654, 5869.882602348014, 5873.442023007808, 5645.125005624135, 5695.40198279183, 5702.429757024264, 5704.372519022904, 5706.229658996065, 5594.272744543184, 5650.152477128381, 5708.4862362387585, 5716.942830571724, 5761.308642962871, 4407.272799987966, 4420.491643461043, 4423.137488189836, 4455.086707225011, 4460.219461594273, 3914.158455931988, 3937.940930886039, 3948.0919388540797, 3967.821448034845, 3972.7020473464604, 5407.351117865803, 5448.700321482356, 5477.945220547823, 5505.504504594605, 5547.445255474501, 5852.707364631751, 5865.530757539472, 5871.412858714094, 5901.76392944286, 5908.4091590841435, 5561.777528898799, 5570.275864137717, 5571.13647384656, 5576.386597474275, 5580.553555715533, 4882.633802464758, 4993.7503124843615, 5038.0, 5058.140116180258, 5063.139266324733, 5921.733521991435, 5926.555508336892, 5943.5839491236065, 5943.851403714855, 5984.970075149597, 5733.226014488074, 5797.391273916178, 5798.449147330997, 5805.564582656233, 5811.302643682616, 5845.152452894392, 5973.342932277359, 6049.697515124139, 6060.666663333559, 6103.267607887082, 5702.144678298288, 5724.656520608777, 5742.224799651994, 5746.971265143815, 5748.252727145445, 5594.916076258778, 5786.884262314814, 5856.765729370777, 5857.121431785266, 5860.0, 5610.887782244414, 5667.659940403589, 5687.488126068508, 5690.374058853606, 5695.943040569541, 5679.6876171810945, 5690.914636280377, 5694.088945768657, 5696.344920334214, 5699.059655156852, 5451.400345962021, 5491.039068162976, 5610.887782244251, 5614.887702246014, 5616.69108199053, 5620.381758006534, 5721.141828725725, 5770.711464426846, 5772.04761214395, 5795.797147099831, 5543.057680194457, 5582.525485333792, 5588.245586845724, 5590.189422533791, 5625.718714064362], "value": 5349.640193026708, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7214209321618, 630.2405601250493, 638.3138126513995, 640.2221301119141, 652.7193306878044, 35772.46341304879, 36490.0, 42761.0, 43754.7812260939, 43762.0, 45597.77201113998, 46115.76942115285, 46192.3071153933, 46202.306965395546, 46240.0, 8807.119288071173, 8993.325500587482, 8997.100289971017, 9007.864882026775, 9007.95496022519, 8827.940647122323, 8983.416077954944, 8988.146126118008, 8988.415752976069, 8991.28069754418, 8994.280457563413, 9034.232090272335, 9059.048799876016, 9064.592093355783, 9065.546722663874, 8975.95512022439, 9023.278137748965, 9036.50299233542, 9038.774030649221, 9046.276297896153, 8741.213290803795, 8837.60230789616, 8843.0714774949, 8843.955780221122, 8847.203751662317, 43104.275828966805, 43408.0, 43507.78246108781, 43529.78235108836, 43531.0, 3527.400341941876, 3603.549556305462, 3717.8512859485586, 3944.467496887912, 4223.366495025771, 32028.359432811212, 32391.352172956646, 32671.836640816644, 32689.50965735516, 33889.491657625156, 4185.518665353493, 4185.748855068675, 4230.51349094855, 4362.171187474401, 4387.1445068211715, 356087.0, 359740.0, 361741.0, 361768.0, 362412.0, 59594.70202648959, 60948.390516094274, 61006.38993610007, 61023.69488152619, 61238.38761612327, 63673.363266366745, 63946.0, 63999.360006400275, 64063.0, 64093.67953160204, 16032.0, 16400.589985250463, 16406.0, 16408.425705100504, 16413.1793410329, 12241.510339586495, 12335.321557314255, 12361.196522226026, 12361.949234315183, 12368.75262494763, 16063.678726425405, 18215.0, 18342.724859127124, 18418.0, 18499.0, 5650.802221922213, 5658.0, 5863.442972917566, 5876.0, 5984.790532331347, 1022.3610243597751, 1088.6298658456162, 1105.1766434006556, 1112.8497652816766, 1118.7370967822555, 31287.217819554236, 32082.679173208435, 32684.0, 32940.941739824135, 33329.83356081847, 1073.8711354637494, 1153.5385845661801, 1166.1079274355125, 1185.8754830742648, 1221.267239656199, 17093.658126837643, 17148.914255428645, 17256.309747610205, 17291.91354043222, 17304.826951730323, 492781.0, 502202.0, 503198.0, 505276.0, 507029.0, 58204.4179558199, 58226.4177358221, 58364.708176459695, 58379.41620583825, 58430.70784646049, 1261.9684507887193, 1279.1046267612521, 1376.064276292131, 1376.7865980772797, 1392.1368751374214, 29833.952912019828, 33358.499622505195, 33362.0, 33729.83135084309, 34039.0, 51193.0, 51504.48495514997, 51555.48444515507, 51648.0, 51829.48170518397, 401690.0, 410808.0, 412628.0, 412644.0, 414195.0, 36739.44890826693, 41211.0, 41288.793556032026, 41837.372439414044, 42381.45711257211, 47431.288530672755, 47660.76169619268, 47814.28278575894, 48901.0, 49215.507844922526, 39718.60281397149, 40073.79963100166, 40794.796026020864, 40937.38593921034, 41183.382249265684, 39537.60462395454, 39824.60175398325, 40872.79563602163, 40919.590804091575, 41215.79392103021, 41169.0, 41187.588124118374, 41288.174236514504, 42184.57815421806, 42192.0, 34725.82637086798, 36483.45274820827, 36958.815205924875, 37312.253754924204, 37565.62434375731, 28198.71801281961, 28328.651405574055, 29931.55102673505, 30830.691693083678, 30928.845355773075], "value": 20354.34027751228, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.95710993235787, 375.20456631940283, 392.497603068073, 397.42373558340404, 401.1335515286981, 14662.266886655678, 14848.0, 17283.913580432098, 19019.144138513766, 19067.33264335747, 16225.513234602951, 18878.244870205177, 18882.905585472054, 18946.24215031397, 18946.810531894676, 5481.780728770845, 5656.151577263402, 5685.971570142144, 5698.202251684767, 5698.629589076718, 4095.262852686515, 5594.384617692051, 5957.255343082114, 6206.317305096436, 6222.253329600454, 5952.375000624937, 6165.383461653843, 6262.780802671908, 6283.5915665481825, 6304.180456540644, 6155.292141403706, 6161.722722477477, 6192.349803270682, 6312.431881130698, 6316.715747791361, 4973.975130124362, 5276.12943864262, 5362.43694412085, 5402.054640437907, 5410.83767486976, 12949.676258093528, 13184.6703832404, 13190.208587484685, 13299.0, 19159.0, 2272.9090836366527, 2277.81777457803, 2461.913833015854, 2738.9726102739114, 2908.171171216193, 9847.60609575616, 9885.110340069356, 10322.896771032269, 11933.16467847251, 14050.227237501933, 1889.3859495663964, 1899.943001709951, 1979.8020197980231, 2016.153215649416, 2176.7823217678106, 71048.6447567764, 75241.0, 85270.57364713198, 86041.56979215126, 87722.56138719329, 9762.511874406393, 9932.702018939442, 9960.452175130438, 10050.296479246528, 10350.792984140275, 13450.0, 13499.325033748273, 13503.189808611514, 13655.658608534866, 13736.244506552235, 7539.924600754032, 7613.314801667766, 7695.576743279174, 7708.768736937901, 7791.259830316111, 7587.924120758722, 7715.498492598075, 7755.883661745078, 7806.55578717939, 7808.726694565777, 4171.79018084752, 4229.13302772937, 4281.400603915455, 4281.785910704514, 4660.930086048712, 1779.0837718574944, 1835.7521734565662, 1868.2807119258912, 1908.1985566062287, 1936.1964784614509, 885.9778505537284, 975.8048390321964, 984.2126298960802, 1011.4891979550428, 1043.6451606453843, 18092.90953545224, 18368.632627347644, 19503.122359493856, 19551.902240488707, 20324.288649897182, 981.1807141037172, 993.2649839119, 1037.1754455208127, 1037.2376303416982, 1066.99466502667, 10492.895071049192, 10693.411862347646, 10881.510332035077, 10971.177161712743, 10977.231593788358, 569627.0, 590166.0, 592690.0, 596940.0, 610946.0, 71404.64297678582, 73549.63225183947, 96543.0, 99953.0, 102658.0, 1026.9229807764298, 1032.9070383665355, 1043.2905624175633, 1060.2684147937898, 1061.1404762142688, 16414.425495107615, 17146.828531714524, 17435.605151588097, 17687.38094166698, 17708.114594270493, 38737.4189387165, 39239.607603924735, 41696.3745543811, 42766.0, 43116.568834311256, 59265.40734592599, 59610.70194648999, 59998.0, 60631.427888064914, 61355.693221533606, 8212.83574328522, 8299.83400332002, 8674.783130421538, 8783.341249406192, 8854.203121719076, 13321.66695832612, 13408.597742067754, 13464.93267533656, 13466.663333416744, 13483.393247303698, 5974.641521508724, 9973.650922217688, 10478.266521343432, 11789.174757766874, 11909.52361905514, 10845.566177353134, 11307.604233851776, 11354.0, 11689.123315751183, 12012.879871201176, 6254.655993920378, 9186.356955013085, 12057.698557535781, 12427.316497592357, 12469.316642253543, 8278.420510564203, 8381.0, 8530.061693213866, 8560.614772335135, 8641.484690126503, 9014.81970360576, 11250.493727782106, 12132.636020919386, 12177.0, 12185.878141218474], "value": 8992.163448865775, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.738166057619, 1468.2291796806671, 1538.7614919687448, 1638.3364737281397, 1793.1572161084293, 8615.095414981422, 8918.821623567534, 8929.598168082435, 8936.59785309661, 8939.793127927727, 8758.255548278405, 8951.955240223806, 8954.597043133059, 8969.372143949926, 8977.551122443885, 2044.090379780998, 2296.1045192374986, 2337.1352599538195, 2350.6366307541584, 2366.881655917206, 2421.69728783902, 2429.0283886445386, 2433.513297340539, 2433.9756602433927, 2434.306222726523, 2380.47629521505, 2388.641703744435, 2400.939976500593, 2410.4576470294132, 2414.4688168602966, 1989.4031790462802, 2262.151693115082, 2303.8156947444163, 2317.849339792925, 2328.2898715891647, 2326.0, 2341.438054866837, 2344.050659482912, 2350.7061617297836, 2352.3883790214495, 8978.820423591556, 9039.909600903973, 9042.638294468214, 9054.637814487412, 9073.455592664395, 5320.707361095138, 5449.264349312872, 5460.31746031746, 5462.6449280797015, 5463.043967305705, 3210.2455922858107, 3247.382997230519, 3290.0623322353254, 3344.4314466540745, 3348.7990720556845, 1041.6250149946013, 1047.984280235797, 1051.5478344311944, 1056.4876035123036, 1057.1806849691475, 12645.93677031609, 12725.30010849403, 12732.299723515203, 12745.61763147107, 12752.234865908074, 4807.18277892759, 4808.687435316763, 4851.539103785135, 4862.95137048632, 4920.581750551243, 10456.424896630606, 10542.578296868192, 10560.47197640115, 10566.894331056592, 10571.577136914591, 5434.809781657623, 5449.536789372948, 5451.37309209446, 5454.645448045864, 5456.0, 4445.888852778707, 4842.806287748521, 4973.776180071905, 4990.2764099205415, 5091.516305950929, 4021.755108040582, 4024.396340548941, 4044.797760111983, 4112.712110152321, 4226.0, 1249.1824395504518, 1266.778313795082, 1294.1781968450102, 1298.8571257161705, 1318.6835159561635, 959.1799011844772, 966.4249771386014, 967.7145242153582, 970.6408628807245, 978.1050338939932, 5801.3328467225965, 5829.8251052468495, 5833.0, 5913.142594323799, 5941.910871336933, 457.2067462951776, 458.81188712627664, 465.87188523155726, 468.6250999200626, 469.2023559948034, 4048.2510735514024, 4057.034390131463, 4096.795160241976, 4127.855525056609, 4162.7502349859105, 10809.40548269837, 11271.549138034386, 11287.153463490271, 11295.661130166109, 11299.039581635652, 10866.782664346669, 11284.379359135326, 11299.0, 11329.830052549218, 11331.43342832855, 8232.259096681328, 8426.241638252586, 8454.61954212062, 8454.704085356983, 8478.957605211934, 5846.612062546537, 6065.484433823174, 6087.208662873798, 6109.633421994695, 6144.723487442986, 10424.0, 10531.0, 10541.367517948947, 10554.947225263824, 10563.419011954416, 10266.743331416774, 10662.306950048222, 10700.785984280426, 10786.784264314512, 10819.1344692426, 7428.554286742813, 7545.471816972759, 7686.884696729665, 7897.13131555517, 8035.035795704553, 8737.169968852948, 8788.604512797066, 8813.691520796741, 8829.2495137914, 8849.247813935888, 7718.228177182462, 8013.278804907586, 8048.315893149148, 8103.865458835649, 8168.224018718212, 7844.450888437755, 7854.646540905787, 7898.802529936797, 7981.92018079835, 8006.07930088024, 6946.479014073863, 7110.288971102848, 7192.460565457507, 7193.604351760494, 7194.100737407824, 4875.512448755097, 4925.581325587222, 4988.326575912205, 5039.521245481674, 5040.949590504195, 5224.738763061908, 5320.361556613231, 5360.276362691143, 5467.562594992471, 5529.889402212014], "value": 4619.516050095154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.2841648914675, 324.14264271003196, 329.3561088072817, 331.5639933487465, 333.082358103425, 5309.150535914255, 5376.8118115865955, 5383.923215356929, 5399.62202645815, 5427.755750991205, 5272.6836389816635, 5274.657147285424, 5295.311609490761, 5299.470052994699, 5308.601854860891, 4029.8388064477385, 4036.777977211252, 4138.97930510347, 4142.937855932163, 4156.459660244179, 4096.918061638766, 4119.567445418233, 4125.64931980782, 4148.23257697327, 4167.3332266837315, 3929.5481019682816, 4135.813888375031, 4142.875713728593, 4161.2301724181125, 4174.290370636999, 4050.7772072536, 4140.612894680271, 4144.461220041375, 4160.791960401968, 4188.643965262956, 3634.3639863024127, 3669.889903302905, 3676.7460797194676, 3685.1892583631825, 3686.5760437549757, 5108.208227724708, 5136.306598609177, 5149.356330458692, 5162.948370516285, 5169.198774190007, 4515.9774201128785, 4666.370040044584, 4694.929576056362, 4726.527347265281, 4729.077829823186, 5487.725613719338, 5534.557235421156, 5545.390007099216, 5545.528630066449, 5550.167474878801, 3773.5471743390694, 3932.7836968966694, 3967.1867267210155, 3974.205158968189, 3981.7810020448865, 5675.048974533261, 5717.514011309044, 5731.971340143273, 5784.1902133701315, 5795.1886735857015, 4006.959930400717, 4010.318245898204, 4028.113814960704, 4035.1727895781296, 4058.553559108496, 3873.6320049595242, 4099.754014759124, 4120.434234990685, 4121.258173528734, 4124.876253712393, 4675.158471475167, 4686.343911852344, 4700.741459219707, 4712.811487540528, 4713.787879545429, 5577.358603760538, 5592.664440133605, 5600.915986260209, 5648.0231918828085, 5654.40628733977, 1346.6162143788972, 1398.3847107272768, 1408.084744915813, 1413.1662319231702, 1415.5541004583524, 894.7226359828344, 895.3329769321897, 906.954652267384, 912.9132732390412, 914.4787471141518, 5321.946780532223, 5351.769093108607, 5377.435369286247, 5418.783248670088, 5427.6472029317965, 5721.256236689203, 5737.282839645044, 5741.25363702716, 5755.510781583528, 5799.318197722698, 5185.637005409661, 5425.37608175057, 5435.619506634576, 5475.780968761284, 5533.142362933671, 3022.7732920031085, 3051.19143426992, 3065.2490139915863, 3065.4635438798123, 3088.675689052662, 5704.572157088235, 5888.440598143169, 5903.701185739216, 5925.73334199962, 5960.8807823844145, 5780.710964451845, 5854.560907931922, 5873.4126587340925, 5906.645601263938, 5913.438223368773, 5881.911771323434, 5956.8384165088255, 5969.880602388015, 5989.640621562633, 6005.849853753691, 5443.537299329601, 5724.8282551523525, 5772.0, 5785.0, 5789.0, 5664.150377443417, 5679.829605111853, 5696.458836410535, 5708.62893911911, 5758.042265629706, 5762.740676669476, 5967.806438712361, 5972.850678733066, 6004.969975150097, 6024.969875150771, 4027.859024934113, 4065.556727361862, 4080.7347522410946, 4082.101937573848, 4109.239790638741, 5803.245578074823, 5816.592838501433, 5819.563532734977, 5831.591788574759, 5846.6784326862435, 5419.214213939035, 5581.888362232814, 5604.495595396434, 5678.971605141948, 5684.516816070681, 5557.805476808292, 5568.359638641608, 5577.888442231051, 5594.4125866785025, 5671.262735844314, 5237.0, 5297.0, 5476.233327334096, 5532.3637781655625, 5540.22436858832, 5562.638428502134, 5577.888442231214, 5624.29696287964, 5647.0, 5649.0, 5487.506124448819, 5534.501894829485, 5536.446355364431, 5537.5569954402745, 5545.7781688732], "value": 4247.570128705786, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.5471698113208, 754.8980887580177, 801.7246517912139, 859.6002858670716, 885.3581153663595, 13317.134386264886, 14046.227457489835, 14057.437702491889, 14078.014538982263, 16186.433474828384, 15730.764038539402, 16306.83693163068, 16319.83680163198, 16342.509724708278, 16360.345586176538, 677.9220389655203, 791.1692722641233, 795.1174196641738, 802.2859654907137, 803.8673618852912, 820.9466384684977, 826.4215049465369, 826.8304997475503, 827.1025936858516, 827.4456114403334, 851.8764779107025, 858.4205661178711, 858.772425307291, 859.2266959736224, 859.9398042137053, 686.4748467422401, 816.9550674712948, 825.7894236969535, 854.0818619983512, 855.5380094748859, 622.3558616831602, 623.1307326279814, 623.1742940603718, 623.1960770605913, 623.5198896849446, 13858.584242472742, 13861.23763193024, 13869.583912482642, 13888.513902013483, 13912.721745565133, 13157.0, 13417.865821341758, 13453.86546134536, 13469.797953030713, 13504.93247533756, 12303.69240768979, 12529.686757831036, 12531.18547294432, 12604.621861344174, 12645.49418023278, 4334.978325108385, 4373.518912919576, 4424.9557504424865, 4426.114777044571, 4571.108633816407, 59967.0, 60242.69878650579, 60540.697296513674, 61211.38788612101, 61527.38472615261, 10650.201234907412, 10684.679459616224, 10918.727031824268, 10988.615398461016, 11139.275947063416, 18808.341708040152, 18843.81156188448, 18975.810241897405, 19006.90496547536, 19046.238150473826, 7995.880061799077, 8207.507549547046, 8221.958890205511, 8265.545395003212, 8320.750377488685, 4572.51988541205, 4658.091672123904, 4762.833300834454, 4775.283707443911, 4901.50643086013, 3248.2691394436533, 3329.267561136546, 3336.099253201622, 3336.1659585103725, 3336.6162891267813, 632.3139393757824, 646.3471893387618, 648.0667838312814, 651.7686221391433, 653.7417720000668, 641.2881701311541, 709.3722055980392, 719.9452801645614, 732.9230430804795, 762.3748526208456, 9065.864012039825, 9682.298045788293, 9722.61109555624, 9743.062488513975, 9849.064338887794, 557.9928229545628, 581.776016233753, 671.5735507952388, 679.0052572980608, 682.8361193313668, 4645.094206629742, 4665.486796452387, 4718.174319494075, 4754.95245047552, 4775.140474714515, 68545.31454685389, 70323.0, 70466.0, 71010.64494677597, 71338.0, 60930.0, 63051.0, 63178.684106579174, 63342.049869252, 64076.0, 56855.715721421955, 57553.0, 57629.13556296743, 58036.0, 58108.709456453296, 8637.438566493158, 8691.478511289342, 8725.694600688945, 8787.694029925418, 8790.164934331227, 37484.43773343456, 38214.5762644601, 38321.43479017957, 39066.60933390629, 39086.804565976985, 46782.53217467782, 46938.29592556046, 47548.0, 49930.0, 52002.73998629983, 12250.87749122533, 12267.509299628271, 12415.503379864704, 12758.872411275768, 12854.035947303802, 13240.470381184645, 13362.398692058687, 13650.522231721841, 13976.301184940916, 14062.718745624825, 13365.131266468037, 13602.183868967893, 13678.726425471235, 14109.435622574982, 14212.715745684822, 13336.19982801035, 13575.117617354841, 13595.0, 13961.232132232824, 14232.0, 13321.467141314239, 13353.39909704085, 13489.460421583419, 13493.662658433617, 13529.797053044016, 10985.725356866142, 11007.0, 11160.55357785712, 11243.66269011931, 11382.14633902444, 10689.625863094754, 10852.348859068481, 11049.39228342449, 11321.830172547583, 11502.367369794742], "value": 6992.897807951269, "unit": "score"}], "name": "dromaeo_css", "value": 7156.722542166403, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 110.0, 119.0, 110.0, 111.0, 110.0, 109.0, 111.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 123.0, 126.0, 181.0, 123.0, 127.0, 155.0, 125.0, 172.0, 134.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 162.0, 161.0, 162.0, 160.0, 162.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 75.0, 75.0, 110.0, 74.0, 75.0, 74.0, 74.0, 97.0, 74.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 121.0, 84.0, 82.0, 83.0, 118.0, 82.0, 83.0, 127.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 55.0, 53.0, 53.0, 70.0, 53.0, 51.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 62.0, 61.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 84.0, 80.0, 80.0, 83.0, 80.0, 79.0, 84.0, 80.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 129.0, 123.0, 121.0, 118.0, 118.0, 128.0, 126.0, 170.0, 125.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 163.0, 160.0, 160.0, 162.0, 162.0, 167.0, 160.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 62.0, 66.0, 62.0, 65.0, 62.0, 62.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1496.0, "alertThreshold": 2.0}]} 22:30:50 INFO - Return code: 0 22:30:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:30:50 ERROR - # TBPL SUCCESS # 22:30:50 INFO - Running post-action listener: _resource_record_post_action 22:30:50 INFO - ##### 22:30:50 INFO - ##### Finished run-tests step (success) 22:30:50 INFO - ##### 22:30:50 INFO - Running post-run listener: _resource_record_post_run 22:30:50 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292921344; Read time: 24; Write time: 351756 22:30:50 INFO - TinderboxPrint: CPU usage
13.1% 22:30:50 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 22:30:50 INFO - TinderboxPrint: I/O write bytes / time
292,921,344 / 351,756 22:30:50 INFO - TinderboxPrint: CPU idle
8,272.7 (86.9%) 22:30:50 INFO - TinderboxPrint: CPU user
1,204.8 (12.7%) 22:30:50 INFO - TinderboxPrint: Swap in / out
0 / 0 22:30:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5242880; Read time: 0; Write time: 548 22:30:50 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 287678464; Read time: 24; Write time: 351208 22:30:51 INFO - Running post-run listener: _upload_blobber_files 22:30:51 INFO - Blob upload gear active. 22:30:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:30:51 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 22:30:51 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:30:51 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:30:51 INFO - (blobuploader) - INFO - Open directory for files ... 22:30:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:30:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:30:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:52 INFO - (blobuploader) - INFO - Done attempting. 22:30:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:30:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:57 INFO - (blobuploader) - INFO - Done attempting. 22:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:58 INFO - (blobuploader) - INFO - Done attempting. 22:30:58 INFO - (blobuploader) - INFO - Iteration through files over. 22:30:58 INFO - Return code: 0 22:30:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:30:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:30:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/926e95a97ed43080752f515d395ca6028f7a437d67d507f7361012ad289f945fc6c6489966e1348b9225f1dd8ea863df73081bf20b5370971e51489026d95c14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0411464e614858aa456a34d4880d3daa19d20911d6ac18a7174b4a847e02e05dbd3c65be820094a6098bcc414417729347a97fe5239efcdefcc3c0ca667b82a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0126c58c8ecf7438d294cc1911acccde41c7c364535be9f72ef39fece38aaa985bf6535dcff7247465f5ae0c73fe10fb9865ad8e59b2f4849c45d0c9170d8fb5"} 22:30:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:30:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:30:58 INFO - Contents: 22:30:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/926e95a97ed43080752f515d395ca6028f7a437d67d507f7361012ad289f945fc6c6489966e1348b9225f1dd8ea863df73081bf20b5370971e51489026d95c14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0411464e614858aa456a34d4880d3daa19d20911d6ac18a7174b4a847e02e05dbd3c65be820094a6098bcc414417729347a97fe5239efcdefcc3c0ca667b82a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0126c58c8ecf7438d294cc1911acccde41c7c364535be9f72ef39fece38aaa985bf6535dcff7247465f5ae0c73fe10fb9865ad8e59b2f4849c45d0c9170d8fb5"} 22:30:58 INFO - Running post-run listener: copy_logs_to_upload_dir 22:30:58 INFO - Copying logs to upload dir... 22:30:58 INFO - mkdir: /builds/slave/test/build/upload/logs 22:30:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.014527 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-24 22:30:57.906668) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 22:30:57.910996) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/926e95a97ed43080752f515d395ca6028f7a437d67d507f7361012ad289f945fc6c6489966e1348b9225f1dd8ea863df73081bf20b5370971e51489026d95c14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0411464e614858aa456a34d4880d3daa19d20911d6ac18a7174b4a847e02e05dbd3c65be820094a6098bcc414417729347a97fe5239efcdefcc3c0ca667b82a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0126c58c8ecf7438d294cc1911acccde41c7c364535be9f72ef39fece38aaa985bf6535dcff7247465f5ae0c73fe10fb9865ad8e59b2f4849c45d0c9170d8fb5"} build_url:https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/O6G9AU2nTISBsYjRGsDyGQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/926e95a97ed43080752f515d395ca6028f7a437d67d507f7361012ad289f945fc6c6489966e1348b9225f1dd8ea863df73081bf20b5370971e51489026d95c14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0411464e614858aa456a34d4880d3daa19d20911d6ac18a7174b4a847e02e05dbd3c65be820094a6098bcc414417729347a97fe5239efcdefcc3c0ca667b82a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0126c58c8ecf7438d294cc1911acccde41c7c364535be9f72ef39fece38aaa985bf6535dcff7247465f5ae0c73fe10fb9865ad8e59b2f4849c45d0c9170d8fb5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 22:30:57.938898) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:30:57.939231) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1464152839.755722-1257291100 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:30:58.021334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 22:30:58.024717) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 22:30:58.025048) ========= ========= Total master_lag: 0.44 =========