builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1463549187.07 results: success (0) buildid: 20160517193059 builduid: 61a00e88093d4fc3a5e7f1b57b9c084f revision: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.067912) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.068370) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.068643) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.153892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.154157) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 22:26:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-17 22:26:27 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.298852) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.299130) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020080 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:27.351243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 22:26:27.351571) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd --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 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd --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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 22:26:27,470 truncating revision to first 12 chars 2016-05-17 22:26:27,470 Setting DEBUG logging. 2016-05-17 22:26:27,470 attempt 1/10 2016-05-17 22:26:27,470 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1ef763f4dc5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 22:26:28,386 unpacking tar archive at: mozilla-inbound-1ef763f4dc5b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.199166 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 22:26:28.567041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:28.567340) ========= script_repo_revision: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:28.567752) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:28.568022) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 22:26:28.577760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-17 22:26:28.578075) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:26:28 INFO - MultiFileLogger online at 20160517 22:26:28 in /builds/slave/test 22:26:28 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:26:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:26:28 INFO - {'append_to_log': False, 22:26:28 INFO - 'base_work_dir': '/builds/slave/test', 22:26:28 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:26:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:26:28 INFO - 'branch': 'Mozilla-Inbound', 22:26:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:26:28 INFO - 'config_files': ('talos/linux_config.py',), 22:26:28 INFO - 'default_actions': ('clobber', 22:26:28 INFO - 'read-buildbot-config', 22:26:28 INFO - 'download-and-extract', 22:26:28 INFO - 'populate-webroot', 22:26:28 INFO - 'create-virtualenv', 22:26:28 INFO - 'install', 22:26:28 INFO - 'run-tests'), 22:26:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:26:28 INFO - 'download_minidump_stackwalk': True, 22:26:28 INFO - 'download_symbols': 'ondemand', 22:26:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:26:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:26:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:26:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:26:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:26:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:26:28 INFO - 'installer_path': 'installer.exe', 22:26:28 INFO - 'log_level': 'info', 22:26:28 INFO - 'log_name': 'talos', 22:26:28 INFO - 'log_to_console': True, 22:26:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:26:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:26:28 INFO - 'opt_config_files': (), 22:26:28 INFO - 'pip_index': False, 22:26:28 INFO - 'sps_profile': False, 22:26:28 INFO - 'sps_profile_interval': 0, 22:26:28 INFO - 'suite': 'dromaeojs', 22:26:28 INFO - 'system_bits': '32', 22:26:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:26:28 INFO - 'title': 'talos-linux64-ix-040', 22:26:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:26:28 INFO - 'use_talos_json': True, 22:26:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:26:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:26:28 INFO - 'work_dir': 'build'} 22:26:28 INFO - ##### 22:26:28 INFO - ##### Running clobber step. 22:26:28 INFO - ##### 22:26:28 INFO - Running pre-action listener: _resource_record_pre_action 22:26:28 INFO - Running main action method: clobber 22:26:28 INFO - rmtree: /builds/slave/test/build 22:26:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:26:29 INFO - Running post-action listener: _resource_record_post_action 22:26:29 INFO - ##### 22:26:29 INFO - ##### Finished clobber step (success) 22:26:29 INFO - ##### 22:26:29 INFO - ##### 22:26:29 INFO - ##### Running read-buildbot-config step. 22:26:29 INFO - ##### 22:26:29 INFO - Running pre-action listener: _resource_record_pre_action 22:26:29 INFO - Running main action method: read_buildbot_config 22:26:29 INFO - Using buildbot properties: 22:26:29 INFO - { 22:26:29 INFO - "project": "", 22:26:29 INFO - "product": "firefox", 22:26:29 INFO - "script_repo_revision": "production", 22:26:29 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:26:29 INFO - "repository": "", 22:26:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:26:29 INFO - "buildid": "20160517193059", 22:26:29 INFO - "pgo_build": "True", 22:26:29 INFO - "basedir": "/builds/slave/test", 22:26:29 INFO - "buildnumber": 1826, 22:26:29 INFO - "slavename": "talos-linux64-ix-040", 22:26:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:26:29 INFO - "platform": "ubuntu64_hw", 22:26:29 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:26:29 INFO - "branch": "mozilla-inbound", 22:26:29 INFO - "repo_path": "integration/mozilla-inbound", 22:26:29 INFO - "revision": "1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd", 22:26:29 INFO - "stage_platform": "linux64-pgo", 22:26:29 INFO - "builduid": "61a00e88093d4fc3a5e7f1b57b9c084f", 22:26:29 INFO - "slavebuilddir": "test" 22:26:29 INFO - } 22:26:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:26:29 INFO - Running post-action listener: _resource_record_post_action 22:26:29 INFO - ##### 22:26:29 INFO - ##### Finished read-buildbot-config step (success) 22:26:29 INFO - ##### 22:26:29 INFO - ##### 22:26:29 INFO - ##### Running download-and-extract step. 22:26:29 INFO - ##### 22:26:29 INFO - Running pre-action listener: _resource_record_pre_action 22:26:29 INFO - Running main action method: download_and_extract 22:26:29 INFO - mkdir: /builds/slave/test/build/tests 22:26: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:26:29 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:26:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:26:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:26:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:37 INFO - Downloaded 1361 bytes. 22:26:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:26:37 INFO - Using the following test package requirements: 22:26:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:26:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:26:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:26:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'jsshell-linux-x86_64.zip'], 22:26:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:26:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:26:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:26:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:26:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:26:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:26:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:26:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:37 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:26:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:26:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:26:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:43 INFO - Downloaded 24347965 bytes. 22:26:43 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:26:43 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:26:44 INFO - Return code: 0 22:26:44 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:26: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:26:44 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:26:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:26:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:26:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:48 INFO - Downloaded 24347965 bytes. 22:26:48 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:26:48 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:26:49 INFO - Return code: 0 22:26:49 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:26:49 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:26:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:26:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:26:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:50 INFO - Downloaded 10840194 bytes. 22:26:50 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:26:50 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:26:50 INFO - Return code: 0 22:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:50 INFO - https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:26:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:26:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:26:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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:26:54 INFO - Downloaded 58386727 bytes. 22:26:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:26:54 INFO - mkdir: /builds/slave/test/properties 22:26:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:26:54 INFO - Writing to file /builds/slave/test/properties/build_url 22:26:54 INFO - Contents: 22:26:54 INFO - build_url:https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:26:54 INFO - Running post-action listener: _resource_record_post_action 22:26:54 INFO - Running post-action listener: set_extra_try_arguments 22:26:54 INFO - ##### 22:26:54 INFO - ##### Finished download-and-extract step (success) 22:26:54 INFO - ##### 22:26:54 INFO - ##### 22:26:54 INFO - ##### Running populate-webroot step. 22:26:54 INFO - ##### 22:26:54 INFO - Running pre-action listener: _resource_record_pre_action 22:26:54 INFO - Running main action method: populate_webroot 22:26:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:26:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:26:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:26:54 INFO - '--tppagecycles', 22:26:54 INFO - '7'], 22:26:54 INFO - 'tests': ['tsvgm']}}, 22:26:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:26:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:26:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:26:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:26:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['damp', 'tps']}, 22:26:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'tests': ['damp', 'tps']}, 22:26:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['dromaeo_dom']}, 22:26:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:26:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'other-e10s': {'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['a11yr', 22:26:54 INFO - 'ts_paint', 22:26:54 INFO - 'tpaint', 22:26:54 INFO - 'sessionrestore', 22:26:54 INFO - 'sessionrestore_no_auto_restore', 22:26:54 INFO - 'tabpaint']}, 22:26:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['tsvgx', 22:26:54 INFO - 'tsvgr_opacity', 22:26:54 INFO - 'tart', 22:26:54 INFO - 'tscrollx', 22:26:54 INFO - 'cart']}, 22:26:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:26:54 INFO - 'tsvgr_opacity', 22:26:54 INFO - 'tart', 22:26:54 INFO - 'tscrollx', 22:26:54 INFO - 'cart']}, 22:26:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'talos_options': ['--disable-e10s'], 22:26:54 INFO - 'tests': ['tp5o']}, 22:26:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'tests': ['tp5o']}, 22:26:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'talos_options': ['--disable-e10s', 22:26:54 INFO - '--xperf_path', 22:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:54 INFO - 'tests': ['tp5n']}, 22:26:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:54 INFO - 'talos_options': ['--xperf_path', 22:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:54 INFO - 'tests': ['tp5n']}}, 22:26:54 INFO - 'talos.zip': {'path': '', 22:26:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:26:54 INFO - Running post-action listener: _resource_record_post_action 22:26:54 INFO - ##### 22:26:54 INFO - ##### Finished populate-webroot step (success) 22:26:54 INFO - ##### 22:26:54 INFO - ##### 22:26:54 INFO - ##### Running create-virtualenv step. 22:26:54 INFO - ##### 22:26:54 INFO - Running pre-action listener: _resource_record_pre_action 22:26:54 INFO - Running main action method: create_virtualenv 22:26:54 INFO - Creating virtualenv /builds/slave/test/build/venv 22:26:54 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:26:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:26:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:26:54 INFO - Using real prefix '/usr' 22:26:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:26:55 INFO - Installing distribute.............................................................................................................................................................................................done. 22:26:57 INFO - Installing pip.................done. 22:26:57 INFO - Return code: 0 22:26:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:26:57 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:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:26:57 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:26:57 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:26:57 INFO - Using env: {'DISPLAY': ':0', 22:26:57 INFO - 'HOME': '/home/cltbld', 22:26:57 INFO - 'LANG': 'en_US.UTF-8', 22:26:57 INFO - 'LANGUAGE': 'en_US:en', 22:26:57 INFO - 'LOGNAME': 'cltbld', 22:26:57 INFO - 'MAIL': '/var/mail/cltbld', 22:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:57 INFO - 'MOZ_NO_REMOTE': '1', 22:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:26:57 INFO - 'NO_EM_RESTART': '1', 22:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:57 INFO - 'PWD': '/builds/slave/test', 22:26:57 INFO - 'SHELL': '/bin/bash', 22:26:57 INFO - 'SHLVL': '1', 22:26:57 INFO - 'TERM': 'linux', 22:26:57 INFO - 'TMOUT': '86400', 22:26:57 INFO - 'USER': 'cltbld', 22:26:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:57 INFO - '_': '/tools/buildbot/bin/python'} 22:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:57 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:26:57 INFO - Cleaning up... 22:26:57 INFO - Return code: 0 22:26:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:26:57 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:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:57 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:26:57 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:26:57 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:26:57 INFO - Using env: {'DISPLAY': ':0', 22:26:57 INFO - 'HOME': '/home/cltbld', 22:26:57 INFO - 'LANG': 'en_US.UTF-8', 22:26:57 INFO - 'LANGUAGE': 'en_US:en', 22:26:57 INFO - 'LOGNAME': 'cltbld', 22:26:57 INFO - 'MAIL': '/var/mail/cltbld', 22:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:57 INFO - 'MOZ_NO_REMOTE': '1', 22:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:26:57 INFO - 'NO_EM_RESTART': '1', 22:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:57 INFO - 'PWD': '/builds/slave/test', 22:26:57 INFO - 'SHELL': '/bin/bash', 22:26:57 INFO - 'SHLVL': '1', 22:26:57 INFO - 'TERM': 'linux', 22:26:57 INFO - 'TMOUT': '86400', 22:26:57 INFO - 'USER': 'cltbld', 22:26:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:57 INFO - '_': '/tools/buildbot/bin/python'} 22:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:57 INFO - Downloading/unpacking psutil>=3.1.1 22:26: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:26: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:26: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:26: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:26: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:26: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:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:26:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:26:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:26:59 INFO - Installing collected packages: psutil 22:26:59 INFO - Running setup.py install for psutil 22:26:59 INFO - building 'psutil._psutil_linux' extension 22:26:59 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:27:00 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:27:00 INFO - building 'psutil._psutil_posix' extension 22:27:00 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:27:00 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:27:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:00 INFO - Successfully installed psutil 22:27:00 INFO - Cleaning up... 22:27:00 INFO - Return code: 0 22:27:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:00 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:00 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:27:00 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:27:00 INFO - Using env: {'DISPLAY': ':0', 22:27:00 INFO - 'HOME': '/home/cltbld', 22:27:00 INFO - 'LANG': 'en_US.UTF-8', 22:27:00 INFO - 'LANGUAGE': 'en_US:en', 22:27:00 INFO - 'LOGNAME': 'cltbld', 22:27:00 INFO - 'MAIL': '/var/mail/cltbld', 22:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:00 INFO - 'MOZ_NO_REMOTE': '1', 22:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:00 INFO - 'NO_EM_RESTART': '1', 22:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:00 INFO - 'PWD': '/builds/slave/test', 22:27:00 INFO - 'SHELL': '/bin/bash', 22:27:00 INFO - 'SHLVL': '1', 22:27:00 INFO - 'TERM': 'linux', 22:27:00 INFO - 'TMOUT': '86400', 22:27:00 INFO - 'USER': 'cltbld', 22:27:00 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:00 INFO - '_': '/tools/buildbot/bin/python'} 22:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:27:00 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:27:00 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:27:00 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:27:00 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:27:00 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:27:00 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:27:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:27:02 INFO - Installing collected packages: mozsystemmonitor 22:27:02 INFO - Running setup.py install for mozsystemmonitor 22:27:02 INFO - Successfully installed mozsystemmonitor 22:27:02 INFO - Cleaning up... 22:27:02 INFO - Return code: 0 22:27:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:02 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:02 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:27:02 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:27:02 INFO - Using env: {'DISPLAY': ':0', 22:27:02 INFO - 'HOME': '/home/cltbld', 22:27:02 INFO - 'LANG': 'en_US.UTF-8', 22:27:02 INFO - 'LANGUAGE': 'en_US:en', 22:27:02 INFO - 'LOGNAME': 'cltbld', 22:27:02 INFO - 'MAIL': '/var/mail/cltbld', 22:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:02 INFO - 'MOZ_NO_REMOTE': '1', 22:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:02 INFO - 'NO_EM_RESTART': '1', 22:27:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:02 INFO - 'PWD': '/builds/slave/test', 22:27:02 INFO - 'SHELL': '/bin/bash', 22:27:02 INFO - 'SHLVL': '1', 22:27:02 INFO - 'TERM': 'linux', 22:27:02 INFO - 'TMOUT': '86400', 22:27:02 INFO - 'USER': 'cltbld', 22:27:02 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:02 INFO - '_': '/tools/buildbot/bin/python'} 22:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:02 INFO - Downloading/unpacking blobuploader==1.2.4 22:27:02 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:27:02 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:27:02 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:27:02 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:27:02 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:27:02 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:27:04 INFO - Downloading blobuploader-1.2.4.tar.gz 22:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:27:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:27:04 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:27:04 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:27:04 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:27:04 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:27:04 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:27:04 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:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:27:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:27:05 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:27:05 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:27:05 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:27:05 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:27:05 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:27:05 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:27:05 INFO - Downloading docopt-0.6.1.tar.gz 22:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:27:05 INFO - Installing collected packages: blobuploader, requests, docopt 22:27:05 INFO - Running setup.py install for blobuploader 22:27:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:27:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:27:05 INFO - Running setup.py install for requests 22:27:05 INFO - Running setup.py install for docopt 22:27:06 INFO - Successfully installed blobuploader requests docopt 22:27:06 INFO - Cleaning up... 22:27:06 INFO - Return code: 0 22:27:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:06 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:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:06 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:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:06 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:06 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:27:06 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:27:06 INFO - Using env: {'DISPLAY': ':0', 22:27:06 INFO - 'HOME': '/home/cltbld', 22:27:06 INFO - 'LANG': 'en_US.UTF-8', 22:27:06 INFO - 'LANGUAGE': 'en_US:en', 22:27:06 INFO - 'LOGNAME': 'cltbld', 22:27:06 INFO - 'MAIL': '/var/mail/cltbld', 22:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:06 INFO - 'MOZ_NO_REMOTE': '1', 22:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:06 INFO - 'NO_EM_RESTART': '1', 22:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:06 INFO - 'PWD': '/builds/slave/test', 22:27:06 INFO - 'SHELL': '/bin/bash', 22:27:06 INFO - 'SHLVL': '1', 22:27:06 INFO - 'TERM': 'linux', 22:27:06 INFO - 'TMOUT': '86400', 22:27:06 INFO - 'USER': 'cltbld', 22:27:06 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:06 INFO - '_': '/tools/buildbot/bin/python'} 22:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:06 INFO - Running setup.py (path:/tmp/pip-NPrSEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:06 INFO - Running setup.py (path:/tmp/pip-BS8XyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:06 INFO - Running setup.py (path:/tmp/pip-2yhxvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:06 INFO - Running setup.py (path:/tmp/pip-xDp2lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:06 INFO - Running setup.py (path:/tmp/pip-LgMcJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:06 INFO - Running setup.py (path:/tmp/pip-cbssQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:06 INFO - Running setup.py (path:/tmp/pip-NILVsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:06 INFO - Running setup.py (path:/tmp/pip-jE5ggn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:06 INFO - Running setup.py (path:/tmp/pip-azRK3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:06 INFO - Running setup.py (path:/tmp/pip-uHj8ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:07 INFO - Running setup.py (path:/tmp/pip-dPENBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:07 INFO - Running setup.py (path:/tmp/pip-Amqrfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:07 INFO - Running setup.py (path:/tmp/pip-kG5A7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:07 INFO - Running setup.py (path:/tmp/pip-j72vtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:07 INFO - Running setup.py (path:/tmp/pip-Cy88Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:07 INFO - Running setup.py (path:/tmp/pip-Axtxh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:07 INFO - Running setup.py (path:/tmp/pip-mPKRga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:27:07 INFO - Running setup.py install for manifestparser 22:27:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:27:07 INFO - Running setup.py install for mozcrash 22:27:07 INFO - Running setup.py install for mozdebug 22:27:07 INFO - Running setup.py install for mozdevice 22:27:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:27:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:27:07 INFO - Running setup.py install for mozfile 22:27:08 INFO - Running setup.py install for mozhttpd 22:27:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozinfo 22:27:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozInstall 22:27:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozleak 22:27:08 INFO - Running setup.py install for mozlog 22:27:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for moznetwork 22:27:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozprocess 22:27:08 INFO - Running setup.py install for mozprofile 22:27:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozrunner 22:27:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:27:08 INFO - Running setup.py install for mozscreenshot 22:27:09 INFO - Running setup.py install for moztest 22:27:09 INFO - Running setup.py install for mozversion 22:27:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:27:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:27:09 INFO - Cleaning up... 22:27:09 INFO - Return code: 0 22:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:09 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:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:09 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:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:09 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:09 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:27:09 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:27:09 INFO - Using env: {'DISPLAY': ':0', 22:27:09 INFO - 'HOME': '/home/cltbld', 22:27:09 INFO - 'LANG': 'en_US.UTF-8', 22:27:09 INFO - 'LANGUAGE': 'en_US:en', 22:27:09 INFO - 'LOGNAME': 'cltbld', 22:27:09 INFO - 'MAIL': '/var/mail/cltbld', 22:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:09 INFO - 'MOZ_NO_REMOTE': '1', 22:27:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:09 INFO - 'NO_EM_RESTART': '1', 22:27:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:09 INFO - 'PWD': '/builds/slave/test', 22:27:09 INFO - 'SHELL': '/bin/bash', 22:27:09 INFO - 'SHLVL': '1', 22:27:09 INFO - 'TERM': 'linux', 22:27:09 INFO - 'TMOUT': '86400', 22:27:09 INFO - 'USER': 'cltbld', 22:27:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:09 INFO - '_': '/tools/buildbot/bin/python'} 22:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:27:09 INFO - Running setup.py (path:/tmp/pip-6nQJoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:27:09 INFO - Running setup.py (path:/tmp/pip-41iNmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:27:09 INFO - Running setup.py (path:/tmp/pip-qrU28C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:27:09 INFO - Running setup.py (path:/tmp/pip-ekq0RQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:27:09 INFO - Running setup.py (path:/tmp/pip-6D8VZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:27:09 INFO - Running setup.py (path:/tmp/pip-mqRM9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:27:09 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:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:27:09 INFO - Running setup.py (path:/tmp/pip-aLZYX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:27:10 INFO - Running setup.py (path:/tmp/pip-Entefk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:27:10 INFO - Running setup.py (path:/tmp/pip-KjV9JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:27:10 INFO - Running setup.py (path:/tmp/pip-0wiK87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:27:10 INFO - Running setup.py (path:/tmp/pip-4dDQfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:27:10 INFO - Running setup.py (path:/tmp/pip-U9VqHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:27:10 INFO - Running setup.py (path:/tmp/pip-NbO8dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:27:10 INFO - Running setup.py (path:/tmp/pip-I9zHkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:10 INFO - Running setup.py (path:/tmp/pip-qFlBcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:27:10 INFO - Running setup.py (path:/tmp/pip-IE_OPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:27:10 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:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:27:10 INFO - Running setup.py (path:/tmp/pip-xPqu1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:27:10 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:27:10 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:27:10 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:27:10 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:27:10 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:27:10 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:27:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:27:10 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:27:10 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:27:10 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:27:10 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:27:10 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:27:10 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:27:12 INFO - Downloading blessings-1.6.tar.gz 22:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:27:12 INFO - Installing collected packages: blessings 22:27:12 INFO - Running setup.py install for blessings 22:27:12 INFO - Successfully installed blessings 22:27:12 INFO - Cleaning up... 22:27:12 INFO - Return code: 0 22:27:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:27:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:13 INFO - Reading from file tmpfile_stdout 22:27:13 INFO - Current package versions: 22:27:13 INFO - argparse == 1.2.1 22:27:13 INFO - blessings == 1.6 22:27:13 INFO - blobuploader == 1.2.4 22:27:13 INFO - docopt == 0.6.1 22:27:13 INFO - manifestparser == 1.1 22:27:13 INFO - mozInstall == 1.12 22:27:13 INFO - mozcrash == 0.17 22:27:13 INFO - mozdebug == 0.1 22:27:13 INFO - mozdevice == 0.48 22:27:13 INFO - mozfile == 1.2 22:27:13 INFO - mozhttpd == 0.7 22:27:13 INFO - mozinfo == 0.9 22:27:13 INFO - mozleak == 0.1 22:27:13 INFO - mozlog == 3.2 22:27:13 INFO - moznetwork == 0.27 22:27:13 INFO - mozprocess == 0.22 22:27:13 INFO - mozprofile == 0.28 22:27:13 INFO - mozrunner == 6.11 22:27:13 INFO - mozscreenshot == 0.1 22:27:13 INFO - mozsystemmonitor == 0.3 22:27:13 INFO - moztest == 0.7 22:27:13 INFO - mozversion == 1.4 22:27:13 INFO - psutil == 3.1.1 22:27:13 INFO - requests == 1.2.3 22:27:13 INFO - wsgiref == 0.1.2 22:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:27:13 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:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:13 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:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:13 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:13 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:27:13 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:27:13 INFO - Using env: {'DISPLAY': ':0', 22:27:13 INFO - 'HOME': '/home/cltbld', 22:27:13 INFO - 'LANG': 'en_US.UTF-8', 22:27:13 INFO - 'LANGUAGE': 'en_US:en', 22:27:13 INFO - 'LOGNAME': 'cltbld', 22:27:13 INFO - 'MAIL': '/var/mail/cltbld', 22:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:13 INFO - 'MOZ_NO_REMOTE': '1', 22:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:13 INFO - 'NO_EM_RESTART': '1', 22:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:13 INFO - 'PWD': '/builds/slave/test', 22:27:13 INFO - 'SHELL': '/bin/bash', 22:27:13 INFO - 'SHLVL': '1', 22:27:13 INFO - 'TERM': 'linux', 22:27:13 INFO - 'TMOUT': '86400', 22:27:13 INFO - 'USER': 'cltbld', 22:27:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:13 INFO - '_': '/tools/buildbot/bin/python'} 22:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 INFO - Cleaning up... 22:27:13 INFO - Return code: 0 22:27:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:27:13 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:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:13 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:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:13 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:13 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:27:13 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:27:13 INFO - Using env: {'DISPLAY': ':0', 22:27:13 INFO - 'HOME': '/home/cltbld', 22:27:13 INFO - 'LANG': 'en_US.UTF-8', 22:27:13 INFO - 'LANGUAGE': 'en_US:en', 22:27:13 INFO - 'LOGNAME': 'cltbld', 22:27:13 INFO - 'MAIL': '/var/mail/cltbld', 22:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:13 INFO - 'MOZ_NO_REMOTE': '1', 22:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:13 INFO - 'NO_EM_RESTART': '1', 22:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:13 INFO - 'PWD': '/builds/slave/test', 22:27:13 INFO - 'SHELL': '/bin/bash', 22:27:13 INFO - 'SHLVL': '1', 22:27:13 INFO - 'TERM': 'linux', 22:27:13 INFO - 'TMOUT': '86400', 22:27:13 INFO - 'USER': 'cltbld', 22:27:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:13 INFO - '_': '/tools/buildbot/bin/python'} 22:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:13 INFO - Downloading/unpacking jsonschema 22:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:13 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:27:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:27:15 INFO - Downloading/unpacking functools32 (from jsonschema) 22:27:15 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:27:15 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:27:15 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:27:15 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:27:15 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:27:15 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:27:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:27:15 INFO - warning: no files found matching '*.txt' 22:27:15 INFO - no previously-included directories found matching 'build' 22:27:15 INFO - no previously-included directories found matching 'dist' 22:27:15 INFO - no previously-included directories found matching '.git*' 22:27:15 INFO - Installing collected packages: jsonschema, functools32 22:27:15 INFO - Running setup.py install for functools32 22:27:15 INFO - warning: no files found matching '*.txt' 22:27:15 INFO - no previously-included directories found matching 'build' 22:27:15 INFO - no previously-included directories found matching 'dist' 22:27:15 INFO - no previously-included directories found matching '.git*' 22:27:15 INFO - Successfully installed jsonschema functools32 22:27:15 INFO - Cleaning up... 22:27:15 INFO - Return code: 0 22:27:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:27:15 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:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:15 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:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:15 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 0x7f6cd214df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x113a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', '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:27:15 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:27:15 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:27:15 INFO - Using env: {'DISPLAY': ':0', 22:27:15 INFO - 'HOME': '/home/cltbld', 22:27:15 INFO - 'LANG': 'en_US.UTF-8', 22:27:15 INFO - 'LANGUAGE': 'en_US:en', 22:27:15 INFO - 'LOGNAME': 'cltbld', 22:27:15 INFO - 'MAIL': '/var/mail/cltbld', 22:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:15 INFO - 'MOZ_NO_REMOTE': '1', 22:27:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:15 INFO - 'NO_EM_RESTART': '1', 22:27:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:15 INFO - 'PWD': '/builds/slave/test', 22:27:15 INFO - 'SHELL': '/bin/bash', 22:27:15 INFO - 'SHLVL': '1', 22:27:15 INFO - 'TERM': 'linux', 22:27:15 INFO - 'TMOUT': '86400', 22:27:15 INFO - 'USER': 'cltbld', 22:27:15 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:15 INFO - '_': '/tools/buildbot/bin/python'} 22:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:16 INFO - Downloading/unpacking flake8 22:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:27:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:27:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 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:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:27:18 INFO - no previously-included directories found matching 'docs/_build' 22:27:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:27:19 INFO - Running setup.py install for pep8 22:27:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:27:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:27:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:27:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:27:19 INFO - no previously-included directories found matching 'docs/_build' 22:27:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:27:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:27:19 INFO - Cleaning up... 22:27:19 INFO - Return code: 0 22:27:19 INFO - Running post-action listener: _resource_record_post_action 22:27:19 INFO - Running post-action listener: _start_resource_monitoring 22:27:19 INFO - Starting resource monitoring. 22:27:19 INFO - ##### 22:27:19 INFO - ##### Finished create-virtualenv step (success) 22:27:19 INFO - ##### 22:27:19 INFO - ##### 22:27:19 INFO - ##### Running install step. 22:27:19 INFO - ##### 22:27:19 INFO - Running pre-action listener: _resource_record_pre_action 22:27:19 INFO - Running main action method: install 22:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:27:19 INFO - Reading from file tmpfile_stdout 22:27:19 INFO - Detecting whether we're running mozinstall >=1.0... 22:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:27:19 INFO - Reading from file tmpfile_stdout 22:27:19 INFO - Output received: 22:27:19 INFO - Usage: mozinstall [options] installer 22:27:19 INFO - Options: 22:27:19 INFO - -h, --help show this help message and exit 22:27:19 INFO - -d DEST, --destination=DEST 22:27:19 INFO - Directory to install application into. [default: 22:27:19 INFO - "/builds/slave/test"] 22:27:19 INFO - --app=APP Application being installed. [default: firefox] 22:27:19 INFO - mkdir: /builds/slave/test/build/application 22:27:19 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:27:19 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:27:36 INFO - Reading from file tmpfile_stdout 22:27:36 INFO - Output received: 22:27:36 INFO - /builds/slave/test/build/application/firefox/firefox 22:27:36 INFO - Running post-action listener: _resource_record_post_action 22:27:36 INFO - ##### 22:27:36 INFO - ##### Finished install step (success) 22:27:36 INFO - ##### 22:27:36 INFO - ##### 22:27:36 INFO - ##### Running run-tests step. 22:27:36 INFO - ##### 22:27:36 INFO - Running pre-action listener: _resource_record_pre_action 22:27:36 INFO - Running main action method: run_tests 22:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:27:36 INFO - Python 2.7.3 22:27:36 INFO - Return code: 0 22:27:36 INFO - grabbing minidump binary from tooltool 22:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:36 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 0x11c99c0>, '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 0x11ca8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11cad50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:27:36 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:27:36 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:27:36 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:27:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:27:36 INFO - Return code: 0 22:27:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:27:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:27:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:27:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:27:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:27:37 INFO - Return code: 0 22:27:37 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/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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:27:37 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/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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:27:37 INFO - Using env: {'DISPLAY': ':0', 22:27:37 INFO - 'HOME': '/home/cltbld', 22:27:37 INFO - 'LANG': 'en_US.UTF-8', 22:27:37 INFO - 'LANGUAGE': 'en_US:en', 22:27:37 INFO - 'LOGNAME': 'cltbld', 22:27:37 INFO - 'MAIL': '/var/mail/cltbld', 22:27:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:27:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:37 INFO - 'MOZ_NO_REMOTE': '1', 22:27:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:37 INFO - 'NO_EM_RESTART': '1', 22:27:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:37 INFO - 'PWD': '/builds/slave/test', 22:27:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:27:37 INFO - 'SHELL': '/bin/bash', 22:27:37 INFO - 'SHLVL': '1', 22:27:37 INFO - 'TERM': 'linux', 22:27:37 INFO - 'TMOUT': '86400', 22:27:37 INFO - 'USER': 'cltbld', 22:27:37 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918', 22:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:37 INFO - '_': '/tools/buildbot/bin/python'} 22:27:37 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/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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:27:37 INFO - mozversion application_buildid: 20160517193059 22:27:37 INFO - mozversion application_changeset: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd 22:27:37 INFO - mozversion application_display_name: Nightly 22:27:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:27:37 INFO - mozversion application_name: Firefox 22:27:37 INFO - mozversion application_remotingname: firefox 22:27:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:27:37 INFO - mozversion application_vendor: Mozilla 22:27:37 INFO - mozversion application_version: 49.0a1 22:27:37 INFO - mozversion platform_buildid: 20160517193059 22:27:37 INFO - mozversion platform_changeset: 1ef763f4dc5b833dacd5e3863b40c5e87ebeecbd 22:27:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:27:37 INFO - mozversion platform_version: 49.0a1 22:27:37 INFO - using testdate: 1463549257 22:27:37 INFO - actual date: 1463549257 22:27:37 INFO - starting webserver on 'localhost:59953' 22:27:37 INFO - SUITE-START | Running 2 tests 22:27:37 INFO - TEST-START | dromaeo_css 22:27:37 INFO - operating with platform_type : linux_ 22:27:37 INFO - Initialising browser for dromaeo_css test... 22:27:37 INFO - TEST-INFO | started process 16526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42roEz/profile http://localhost:59953/getInfo.html) 22:27:41 INFO - PROCESS | 16526 | __metrics Screen width/height:1600/1200 22:27:41 INFO - PROCESS | 16526 | colorDepth:24 22:27:41 INFO - PROCESS | 16526 | Browser inner width/height: 1024/697 22:27:41 INFO - PROCESS | 16526 | __metrics 22:27:42 ERROR - PROCESS | 16526 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:27:43 INFO - TEST-INFO | 16526: exit 0 22:27:43 INFO - Browser initialized. 22:27:43 INFO - Running cycle 1/1 for dromaeo_css test... 22:27:43 INFO - TEST-INFO | started process 16608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42roEz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:55 INFO - PROCESS | 16608 | RSS: Main: 152915968 22:27:55 INFO - PROCESS | 16608 | 22:28:46 INFO - PROCESS | 16608 | 1463549326513 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:30:54 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59953/tests/dromaeo/cssquery-ext.html) 22:30:55 INFO - PROCESS | 16608 | RSS: Main: 256651264 22:30:55 INFO - PROCESS | 16608 | 22:33:57 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-ext.html (next: http://localhost:59953/tests/dromaeo/cssquery-jquery.html) 22:33:58 INFO - PROCESS | 16608 | RSS: Main: 236920832 22:33:58 INFO - PROCESS | 16608 | 22:37:00 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59953/tests/dromaeo/cssquery-mootools.html) 22:37:00 INFO - PROCESS | 16608 | RSS: Main: 243490816 22:37:00 INFO - PROCESS | 16608 | 22:40:02 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59953/tests/dromaeo/cssquery-prototype.html) 22:40:03 INFO - PROCESS | 16608 | RSS: Main: 231682048 22:40:03 INFO - PROCESS | 16608 | 22:43:05 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59953/tests/dromaeo/cssquery-yui.html) 22:43:06 INFO - PROCESS | 16608 | RSS: Main: 222261248 22:43:06 INFO - PROCESS | 16608 | 22:46:08 INFO - PROCESS | 16608 | Cycle 1(1): loaded http://localhost:59953/tests/dromaeo/cssquery-yui.html (next: http://localhost:59953/tests/dromaeo/cssquery-dojo.html) 22:46:09 INFO - PROCESS | 16608 | RSS: Main: 268500992 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | __start_tp_report 22:46:09 INFO - PROCESS | 16608 | _x_x_mozilla_page_load 22:46:09 INFO - PROCESS | 16608 | _x_x_mozilla_page_load_details 22:46:09 INFO - PROCESS | 16608 | |i|pagename|runs| 22:46:09 INFO - PROCESS | 16608 | |0;dojo.html;3168.065420700893;3169.9524507132382;3181.2524056846646;3210.7270881975032;3236.0291912426264;5328.706921119337;5394.973025134869;5501.257330260413;5624.15637654352;5628.324601047875;5485.396606373296;5543.390227075019;5545.251391062206;5559.916601250974;5574.804881829137;4982.177940639799;5036.6726162799305;5084.237364395333;5098.668586541863;5234.084035293828;5125.2055931330515;5153.6392452528335;5187.429382767892;5205.245239440298;5220.4779522047875;4797.328374027639;4922.3354847095725;4947.6784009039475;4971.130052240879;5026.8240611578785;4996.825111121093;5008.574271186953;5084.720340381277;5087.033463641897;5136.897262054776;4831.516848315176;5048.823291184791;5051.292819005343;5072.492750724898;5072.9746351268;5391.649542779746;5469.945300546983;5474.09677403228;5522.641028333145;5561.110222364443;5956.642601443928;5990;5992.041273396279;6010.248718910137;6015.939840601582;5732.0255556555485;5853.88292234153;5922.58541902067;5954.225950626391;5980.940190598082;5541.9168712469345;5639.746211420496;5648.491635752761;5651.463111004448;5652.689102099424;6131.193168498116;6132.938670613326;6137.693115344215;6151.108089327023;6159.6612186330185;5558.055130627803;5775.913361299584;5834.591578589543;5939.851503712355;5956.344802071769;5957.434043765836;5980.13288073227;6001.219841420588;6015.699215039318;6018.067199584071;5881.32364778056;5924.763009479659;5926.703664816828;5933.762649494059;5939.2575928009;5636.4927156556105;5800.129980502874;5876.970615146897;5894.2926848778425;5933.970330148321;4431.069475410135;4431.1137772445;4431.645468362523;4433.069055498381;4463.687541872103;3924.1955399143108;3938.4683067786054;3950.6839452843706;3992.6207010333974;4022;5349.384820745664;5387.8114266001285;5477.8356649299785;5580.553555715533;5583.804566840141;5953.077273022688;5982.282126144891;5990.880182396328;5995.400459953969;5998.1002849572915;5602.047651899187;5612.915806262909;5616.269884914935;5619.157126430986;5623.8594035149445;4933.407991041098;5009.499050095034;5072.264521644414;5100.948990510122;5121.795128194831;6175.96912015437;6184.5052395808225;6195.597286176384;6198.349173336825;6204.658743769136;5580.944190558205;5969.134475500941;5989.850253743692;5991.191189189403;5994.460498554977;6113.749968913837;6192.102145189013;6198.659073750807;6295.716692748746;6310.7791227306825;5927.842746951978;5964.522838173022;5965.2245208124405;5974.880502389841;6032.245969253843;5734.455226753452;5884.705764711833;6015.909761353671;6063.393660633901;6082.7262773174425;5726.828195154152;5864.706764661664;5873.647581145145;5879.382664820132;5928.258967629046;5841.561882858888;5849.093390524476;5849.122631605123;5852.502537284378;5855.03391940325;5499.945000549943;5613.101903695389;5687.658740475585;5745.770169193185;5761.452661997104;5862.94137058624;5876.324222714443;5911.527077833849;5917.615355001911;5920.851978700567;5718.942810571841;5725.456081672234;5725.627834190765;5734.655920644775;5762.423757624204 22:46:09 INFO - PROCESS | 16608 | |1;ext.html;604.7127614383165;638.5944924972642;640.7725257533573;653.6796969484951;655.6197405504807;36320;37013.25973480529;37082;37125.44311835324;37163.81418092913;45816.54183458164;46648.53351466484;46975.29537056947;49997.50002499974;50005.749971250094;8947.970983336902;9125.634974601007;9125.771855703593;9126.178643922045;9130.72607821763;8893.68872089477;9019.413738107034;9021.323400744937;9021.503817290046;9022.45865248084;9089.590968406404;9132.771680707969;9135.086491350845;9138.725838224864;9155.86266206004;9064.77338066547;9089.500077495735;9136.908630913738;9137.5888085036;9141.085891410872;8982.416142950753;9076.092390760938;9086.727398178065;9092.363534552584;9095.681651142178;42412.363814542805;42523.362149567474;42811.35782963258;42824.57175428237;42847.785761071;3870.438786375988;4306.80619372129;4386.298192289219;4487.12501062293;4498.775061246924;32256.516152257736;32911.34177316464;33036.33927321464;33483.49774753356;33598.83200584006;4362.541933097011;4363.563643635643;4488.192125417424;4501.23479008566;4721.740304283263;349337;350615.99999999744;350939;351722;351930;36231.4565281521;59550.70224648849;59807;60204;60258;36670;38743.2251354977;38837.6116238834;38853;38858.2228355437;15595;15713.528594142193;15723.292451839694;15740.842591574166;15741.527754167393;13352.732945341038;13449;13454.394552245172;13456.730865382637;13461.86538134606;8762.780930476789;8765.956170219235;8778.91221087781;8822.161894620001;8838.734837954871;3357.8992630221132;3359.428897087501;3415.7438192135687;3444.4144495435835;3469.965300347015;1040.7086015915404;1100.5597760895546;1146.8107762219308;1151.9712007199719;1154.976900462003;33168.50247246293;33621.49567756485;33826.49260261098;33871.49192762111;34130.84966800547;1116.625930313338;1134.4384529657873;1158.44394690549;1175.6649354933975;1246.3269834289563;17467.126643667765;17572.648547028988;17649.294028238983;17708;17743.290268389377;583079;585079;589549;589948;590108;59574;59938.40061599416;60031.69984150139;60074.699626501584;60141.39858601358;1291.9870801292054;1395.8743713065874;1420.7584710599224;1536.500637292862;1650.4471002214393;31035.982661126953;33609.3278134431;35720.64279357173;35814;37614;57714.134287984874;58444.12333814911;58447;58641;58667.70666146642;496777;502500;502926;503523;504002;44926.3261051091;45822.31266530938;46721.29918051164;46735.76632116954;46749.76625116853;46021;50283.49716502788;50528.24207636815;50719;51874.22188667249;41769;42387.152256955305;42815.78592107019;43595.56404435915;43819.342709860015;41408.1718365625;42828.78585607052;42858.357124642534;42903.785481072395;43072.56927430686;42017.78991105147;42425.36361954511;43241.13517729691;43287.783561082;43669.56330436655;37472;37643.43534846925;38005.80997095107;38462.6153738459;38574.42138367983;29158.27104322409;29175.270618234714;29410.55884161782;29516;29574.556381653863 22:46:09 INFO - PROCESS | 16608 | |2;jquery.html;388.95138107736534;391.9666828319593;395.9564447910731;396.0771402631868;413.86135644559073;17729.7340539892;18494.815051849477;19186.136623851926;19204.71192932107;19626.607467850634;18290.359837405693;18544.629107417844;18761.7185742214;19311.420657380266;19346.709799352986;5537.833864984057;5615.073512870372;5752.137179423099;5754.74103665336;5764.769409223626;5568.805091821787;5617.691026993513;5642.605017648766;5649.717514124277;5650.858728531799;5438.265834112403;5586.91619625706;5641.238432811559;5659.717014149276;5685.602007859451;5643.492085712285;5648.3222013358245;5649.265595472562;5656.830295091175;5692.686902220376;4888.462269150391;5146.742662866842;5152.793888244466;5153.2270159476;5156.587473002151;22246.77753222463;22495.21266755672;22808.77191228083;22908;22972.885135574215;2426.259990702835;2427.041318679112;2428.5992811186134;2450.093465417789;2547.8853451594723;14239.430422783076;14447.566573002721;14659.853401466062;14708;15078.321475533518;1969.3402710092137;2003.378952524722;2012.4264584593468;2048.9077991490417;2084.322595156565;71549.64225178893;73950.26049739488;75179.62410187913;75500.24499755041;77724;10789.352638841694;11185.49665265065;11258;11333.356663283777;11580.710482238012;15879.364825407085;15893.364265429253;16021.359145634277;16042.43851465193;16044.358225671076;8356.540390278593;8454.492730436194;8508.61711222984;8623.845908946236;8659.567021648892;8093.3929955252415;8175.182481751777;8313.875291870627;8336.416450848503;8352.749417517483;4869.513048695102;4871.464138944713;4905.141600219948;4974.427940786783;5041.521055499722;2002.8097330753847;2050.682144267611;2063.3912995666315;2070.9482262943247;2100.915963361479;987.9555420006117;1019.5961404555825;1100.1254003067581;1119.8656161260537;1171.824226366035;17612.471625851245;19042.42872713821;19846.503837403892;19908.70136947947;19911.502212444517;1015.7765291635828;1045.7856139491387;1063.547992103353;1073.2648136026846;1138.9316641001567;10297.227707921937;11149.609763658233;11217.831732524019;11229.606963756229;11241.494132764044;476537;477578.0595765035;485208;485638;493662;72813.63593182;75862.62068689731;96643;98465.50767246261;104292.47853760683;1035.5236591167995;1076.1444651502075;1097.7530055737395;1110.3171549497163;1112.449337577904;17708.114594270493;17892.642147157243;18016.729749054037;18016.90991545034;18080.72878906791;40369.798151009054;42071.57928420677;42429.36355954725;44357.778211108736;44775.328370073825;59719;60004.69997649984;60116.69941650264;60660.393396065476;61094.38905610887;7339.77980660581;7853.253940875607;8652.307815374881;8779.463593871174;9037.77405564866;12211.023118150704;12539.310337931502;12574.87425125737;12757.55348562833;13153.868461315264;10877.891221087688;11253.324800511995;11275.379854108103;11625.6512304631;11888.702782430162;11159;11249.71875703114;11372;11458.656240312805;11630.76738465209;11289.548418063514;11342.092632589209;11413.942930285295;11471.254368466023;11472.770544589228;8202.138775428493;8209.958950205211;8216.301614362606;8259.669613215645;8453.281471075028;8537.359698022798;10332.896671033193;10948.39783811866;11305;11446.255993360404 22:46:09 INFO - PROCESS | 16608 | |3;mootools.html;1433.1902475101574;1567.3573834727763;1589.5390336802327;1761.832625900539;1881.0970734047658;8760.474371537704;9148.725538233846;9162.221211197048;9435.433873967566;9448.763780905481;9166.816663666723;9346.953265233682;9386.108319709634;9395.906040939588;9404.247660187186;2099.632564301241;2363.3028256664325;2429.538387706339;2458.4960083182905;2501.9374515637164;2442.9389265268333;2462.938426539342;2464.039024780337;2470.1354525915967;2473.6413220083077;2490.1657944588583;2514.6479492871013;2515.8993640254366;2519.6976362836394;2520.5085008423275;2082.187946700788;2407.6629271901948;2441.7924476419525;2468.2968751561366;2476.232367965936;2403.134871446278;2408.0608562660577;2415.7221919479307;2427.9393015174587;2462.876856157203;9372.203362714177;9395.34232603718;9428.292878034175;9445.574949127236;9449.433034017911;5435.891282174334;5579.944200557983;5581.972090139564;5584.162375643646;5593.32880054392;3614.34941710492;3664.1022949377575;3684.373656478405;3702.8703995360033;3713.6843368313725;1166.1778446195412;1226.926384416938;1233.5004323249098;1248.4756402311052;1275.8086287056924;13504.189748615114;13506.986975976939;13517.188968661912;13521.661958451019;13531.323433828367;4959.429665588431;5057.342545469065;5060.418051924003;5068.493150684976;5141.891824352073;10983.39591322469;10995.890041099487;11050.010998020434;11055.226134170693;11055.557777688964;4885.389326334208;5374.543163831118;5462.535684466865;5462.7268636568015;5492.148716948879;5056.898862022739;5225.9738701307015;5229.424763276036;5279.8152064678325;5285.127953887565;4242.896846819849;4257.936130958038;4301.354796780508;4465.799039043251;4474.172278128557;1328.0570794735652;1332.8267325247655;1333.6999175185506;1343.4156142078227;1344.3076815440056;1020.1430798129459;1022.8772547294373;1024.5952848624752;1035.994820025895;1044.0968562193648;6188.071789231652;6217.937820621826;6224.284207316216;6252.874942501216;6440.25937017249;434.06459080681185;441.65771527066136;447.5189171640499;449.64028776978284;452.4751288505318;4158;4311.754616888053;4334.612923864333;4357.934630980538;4397.516273209944;11236.269642473213;11566.65300041;11571.190016698918;11573.363465009337;11640.592579259684;11376.317420954603;11456.427178641201;11470.680871699695;11693.590724324607;11741.236819606698;8552.444091134057;8563.209508472935;8641.524716140548;8716.215540601377;8731.345149113715;6215.06773983888;6328.968355158195;6365.3316401779;6383.297837237993;6434.967825161031;10607.20445966571;10617.41604211745;10717.785644287227;10765.838512422164;10833.891661083288;10890.836637450286;10919.181061420584;11071.723206919569;11072.778544429228;11200.159988001096;7230.74692385764;7361.705531778883;7597.088349398107;7845.450818442654;7928.286454218917;9015.14356136166;9074;9084.40951338161;9137.314701397288;9182.816343672956;7682.961585192038;7869.6458659361615;7952.84094318122;8004.91995080065;8072.556009419538;7692.615369231628;7770.844583108194;7816.257455541714;7888.526688398714;8119.75640730779;7568.4323675723435;7577.507462014952;7629.847403052019;7643.350315223268;7676.270754278335;5334.439883812299;5378.247045413567;5388.54197393226;5469.3983661797965;5484.396716361117;5092;5248.343957005374;5251.36983561975;5298.099323115002;5367.651102678314 22:46:09 INFO - PROCESS | 16608 | |4;prototype.html;327.1624640919247;339.93031428557146;345.8772135891758;351.2325568632538;353.77712041413906;5330.866728331784;5349.679019258847;5359.999999999995;5372.247885296062;5398.811041613544;5303.549198318147;5338.199270109486;5345.412004679482;5352.2506849041065;5372.2210279509545;3915.37354023357;4027.758334499925;4133.627973482386;4154.335306350985;4191.140816132701;4104.548499665035;4126.46355973723;4140.136938377742;4150.8132134053885;4183.477065366829;3917.5494818096;4141.482314710661;4142.378643203513;4165.520965089023;4165.937510937339;3922.5881282465216;4030.536488303824;4037.899052523696;4133.607307305801;4148.771817550033;3631.89104326868;3639.526861507987;3654.3604869147794;3655.7258205634685;3659.9268014639824;5131.512506311932;5152.304438900738;5172.431032586413;5180.274761533388;5190.92213616796;4616.307553866914;4714.387129673155;4731.408573928259;4773.856784296442;4791.4250289965075;5462.153366228241;5515.696636684981;5522.30971128609;5533.778648854041;5565.858998905214;4067.735597186203;4067.97966010171;4083;4097.180563887205;4111.033907031845;5665.830025099212;5735.340435849889;5740.684262365568;5742.626729262584;5743.425657434265;4040;4066.8169932353117;4071.592840715964;4088;4100.077482566398;4171.478565179353;4178.641941369006;4188.183304255702;4188.602082802129;4191.44321963608;4549.067441174551;4683.501279590499;4688.46082700487;4693.967327188013;4767.856964291007;5503.816679413865;5517.868836888509;5533.983978731601;5537.446255374511;5551.417101204396;1417.4684493315008;1422.7581311177125;1424.223798030076;1435.9210243436712;1445.1184777285848;930.3812964378748;956.516958935733;959.6497278493439;999.1257649556638;999.7100840756099;5377.279270633359;5393.325834270716;5402.109261758354;5417.512423881791;5440.183972404092;5714.285714285715;5724.799632012943;5744.569157313218;5779;5791;5208.661437006583;5316.521513063843;5341.172118321666;5383.300170977748;5391.652086978255;2995.445842519141;2996.9700302997126;3019.0942717184757;3021.214484234115;3032.348045170299;5600.607957443021;5795.290389335156;5818.679972601548;5830.746389526273;5874.591417518796;5685.800996965087;5687.488126068673;5723.456271654186;5759.481646651738;5817.720101577646;5778.497590626467;5780.5953583248765;5830.8542286442325;5870.295564532284;5875.588708790344;5609.046462101534;5636.436356364331;5721.141828725725;5735.3977832327;5743.741531631003;5546.001719690333;5619.269494965628;5621.498433197352;5658.603897727119;5688.431156884444;5636.351819540641;5842.474177324061;5852.7658893645485;5861.856937897069;5901.645901245939;4078.4494093297026;4084.7541499842832;4086.959130408658;4090.1001779608437;4112.259793237246;5406.621536492407;5591.909577632323;5636.605437619327;5649.435056494318;5691.23168372281;5390.703511306916;5468;5473.425290344457;5497.038018346693;5569.944300557105;5533.944660553343;5551.278333816578;5553.861458400937;5554.444555544414;5565.6104072714525;5323.707196104251;5496.5053145217125;5521.282233309644;5553.361363443094;5567.4432556744005;5641.492265696107;5643.52030077448;5646.435356464485;5658.17956396312;5674.57440691958;5221.086309895829;5463.644863083887;5511.22842802;5542.224088627515;5554.861128471821 22:46:09 INFO - PROCESS | 16608 | |5;yui.html;729.6388287797538;734.3721118443731;756.6746299091388;763.8472305538897;802.2418814220562;14829.925850370746;15293.617659558517;15521.767173492379;15591.298391572363;15602.531924042269;14209.218492982882;14373.137611743277;14416.135031898068;14423.134611923266;14500.709985800278;823.3618945317397;830.3855147191065;832.0182185021672;835.1356346181703;837.8240569480416;693.5076095971841;806.6410447350931;835.3859912963967;835.4694768821812;836.7406104107715;879.8020445399798;881.4755220643707;881.7442941546943;881.9250363719092;881.9294456443502;723.8226634474587;843.2832092721138;876.8991565969932;877.5349064995562;878.7100256915203;632.6646877155115;633.5628416392674;634.1344065350809;635.7075745157233;636.1634450697318;14845;14867.03364281329;14873.33070011852;14893.70212595742;14921.477748278758;13829.51596694121;14275.286235688174;14278.286085695674;14343;14509.419623215059;11485.196036277464;11729.824052639216;13081.672958176026;13378.93310533441;13404.731905361936;4596.103759766846;4631.073785242931;4634.629229661619;4657.138429390574;4663.88340291492;52868.471315287126;58124;59828;60308.39691603115;60415.395846041414;11272.830907536394;11363.772724545463;11573.826392604118;11656.53373865036;11940.820887686692;20110.296139635044;20165.49586260355;20178.091985860672;20199.39401817948;20249.696254556195;8534.573271336409;8564.74305770828;8677.783055423539;8746;8915.465072095694;4961.677490963148;5013.699178049329;5022.171341728574;5033.748312584429;5153.994970980698;3513.718902487795;3609.7473176877893;3611.2235869287706;3618.0773902654655;3620.619834917296;665.4809248785955;692.5117791956659;724.7825652304288;731.3308322884501;732.2018999290799;591.8520369907524;718.2386670129679;742.2466196810242;813.6989313953875;828.3704384667695;9932;10304.433256170983;10400.230520061075;10459.738506537398;10489.055984961391;601.506764453153;615.4676205082661;725.3906718356606;773.377431167904;818.6520728690402;4580.908381832344;4860.36815214027;4900.338454308694;4950.702957822542;4961.925571116436;69501;69790;70444.29555704378;70560;73173.634131829;47479;52549.2117618236;59144.40855591475;63994.36005639884;65309.346906531275;54930;55163.724181378835;55479;56300;56682;9414.62341506332;9543.665971690958;9568.856467153137;9583.472908989794;9616.615335386507;37694;38501.80749096349;38661.42007869828;38928.221435570566;39618.405723914744;49525;52174.47825521696;52295.73852130715;52526.737366312926;53830.73084634552;11920.344381059125;11992.580259690869;12212.511499539918;12228.32744199042;12357.93821030925;13292.659373746494;13439.715248407598;13441;13595.796063058864;13892.583222503341;11727.824082638937;11841;12700.682482938;12873.613791585893;13375.398107085399;13556.59330220095;14061.789073164115;14141.646458838612;14366;14531.200783956561;13931.791023134863;13984;14099.577012689635;14135.363908624342;14285.357158927665;11454.713632159262;11562.248453850809;11627.186096973131;11942.940285298519;11979.161458697807;11426.25729327591;11703.2978021319;11777.941110294736;11905.16663833558;11946.940265298617 22:46:09 INFO - PROCESS | 16608 | __end_tp_report 22:46:09 INFO - PROCESS | 16608 | __start_cc_report 22:46:09 INFO - PROCESS | 16608 | _x_x_mozilla_cycle_collect,863 22:46:09 INFO - PROCESS | 16608 | __end_cc_report 22:46:09 INFO - PROCESS | 16608 | __startTimestamp1463550369137__endTimestamp 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | ------- Summary: start ------- 22:46:09 INFO - PROCESS | 16608 | Number of tests: 6 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#0] dojo.html Cycles:165 Average:5514.26 Median:5725.46 stddev:652.72 (11.4%) stddev-sans-first:628.24 22:46:09 INFO - PROCESS | 16608 | Values: 3168.1 3170.0 3181.3 3210.7 3236.0 5328.7 5395.0 5501.3 5624.2 5628.3 5485.4 5543.4 5545.3 5559.9 5574.8 4982.2 5036.7 5084.2 5098.7 5234.1 5125.2 5153.6 5187.4 5205.2 5220.5 4797.3 4922.3 4947.7 4971.1 5026.8 4996.8 5008.6 5084.7 5087.0 5136.9 4831.5 5048.8 5051.3 5072.5 5073.0 5391.6 5469.9 5474.1 5522.6 5561.1 5956.6 5990.0 5992.0 6010.2 6015.9 5732.0 5853.9 5922.6 5954.2 5980.9 5541.9 5639.7 5648.5 5651.5 5652.7 6131.2 6132.9 6137.7 6151.1 6159.7 5558.1 5775.9 5834.6 5939.9 5956.3 5957.4 5980.1 6001.2 6015.7 6018.1 5881.3 5924.8 5926.7 5933.8 5939.3 5636.5 5800.1 5877.0 5894.3 5934.0 4431.1 4431.1 4431.6 4433.1 4463.7 3924.2 3938.5 3950.7 3992.6 4022.0 5349.4 5387.8 5477.8 5580.6 5583.8 5953.1 5982.3 5990.9 5995.4 5998.1 5602.0 5612.9 5616.3 5619.2 5623.9 4933.4 5009.5 5072.3 5100.9 5121.8 6176.0 6184.5 6195.6 6198.3 6204.7 5580.9 5969.1 5989.9 5991.2 5994.5 6113.7 6192.1 6198.7 6295.7 6310.8 5927.8 5964.5 5965.2 5974.9 6032.2 5734.5 5884.7 6015.9 6063.4 6082.7 5726.8 5864.7 5873.6 5879.4 5928.3 5841.6 5849.1 5849.1 5852.5 5855.0 5499.9 5613.1 5687.7 5745.8 5761.5 5862.9 5876.3 5911.5 5917.6 5920.9 5718.9 5725.5 5725.6 5734.7 5762.4 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#1] ext.html Cycles:180 Average:63634.45 Median:23605.30 stddev:130405.94 (552.4%) stddev-sans-first:130685.90 22:46:09 INFO - PROCESS | 16608 | Values: 604.7 638.6 640.8 653.7 655.6 36320.0 37013.3 37082.0 37125.4 37163.8 45816.5 46648.5 46975.3 49997.5 50005.7 8948.0 9125.6 9125.8 9126.2 9130.7 8893.7 9019.4 9021.3 9021.5 9022.5 9089.6 9132.8 9135.1 9138.7 9155.9 9064.8 9089.5 9136.9 9137.6 9141.1 8982.4 9076.1 9086.7 9092.4 9095.7 42412.4 42523.4 42811.4 42824.6 42847.8 3870.4 4306.8 4386.3 4487.1 4498.8 32256.5 32911.3 33036.3 33483.5 33598.8 4362.5 4363.6 4488.2 4501.2 4721.7 349337.0 350616.0 350939.0 351722.0 351930.0 36231.5 59550.7 59807.0 60204.0 60258.0 36670.0 38743.2 38837.6 38853.0 38858.2 15595.0 15713.5 15723.3 15740.8 15741.5 13352.7 13449.0 13454.4 13456.7 13461.9 8762.8 8766.0 8778.9 8822.2 8838.7 3357.9 3359.4 3415.7 3444.4 3470.0 1040.7 1100.6 1146.8 1152.0 1155.0 33168.5 33621.5 33826.5 33871.5 34130.8 1116.6 1134.4 1158.4 1175.7 1246.3 17467.1 17572.6 17649.3 17708.0 17743.3 583079.0 585079.0 589549.0 589948.0 590108.0 59574.0 59938.4 60031.7 60074.7 60141.4 1292.0 1395.9 1420.8 1536.5 1650.4 31036.0 33609.3 35720.6 35814.0 37614.0 57714.1 58444.1 58447.0 58641.0 58667.7 496777.0 502500.0 502926.0 503523.0 504002.0 44926.3 45822.3 46721.3 46735.8 46749.8 46021.0 50283.5 50528.2 50719.0 51874.2 41769.0 42387.2 42815.8 43595.6 43819.3 41408.2 42828.8 42858.4 42903.8 43072.6 42017.8 42425.4 43241.1 43287.8 43669.6 37472.0 37643.4 38005.8 38462.6 38574.4 29158.3 29175.3 29410.6 29516.0 29574.6 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#2] jquery.html Cycles:180 Average:28754.39 Median:22651.99 stddev:79681.40 (351.8%) stddev-sans-first:79876.46 22:46:09 INFO - PROCESS | 16608 | Values: 389.0 392.0 396.0 396.1 413.9 17729.7 18494.8 19186.1 19204.7 19626.6 18290.4 18544.6 18761.7 19311.4 19346.7 5537.8 5615.1 5752.1 5754.7 5764.8 5568.8 5617.7 5642.6 5649.7 5650.9 5438.3 5586.9 5641.2 5659.7 5685.6 5643.5 5648.3 5649.3 5656.8 5692.7 4888.5 5146.7 5152.8 5153.2 5156.6 22246.8 22495.2 22808.8 22908.0 22972.9 2426.3 2427.0 2428.6 2450.1 2547.9 14239.4 14447.6 14659.9 14708.0 15078.3 1969.3 2003.4 2012.4 2048.9 2084.3 71549.6 73950.3 75179.6 75500.2 77724.0 10789.4 11185.5 11258.0 11333.4 11580.7 15879.4 15893.4 16021.4 16042.4 16044.4 8356.5 8454.5 8508.6 8623.8 8659.6 8093.4 8175.2 8313.9 8336.4 8352.7 4869.5 4871.5 4905.1 4974.4 5041.5 2002.8 2050.7 2063.4 2070.9 2100.9 988.0 1019.6 1100.1 1119.9 1171.8 17612.5 19042.4 19846.5 19908.7 19911.5 1015.8 1045.8 1063.5 1073.3 1138.9 10297.2 11149.6 11217.8 11229.6 11241.5 476537.0 477578.1 485208.0 485638.0 493662.0 72813.6 75862.6 96643.0 98465.5 104292.5 1035.5 1076.1 1097.8 1110.3 1112.4 17708.1 17892.6 18016.7 18016.9 18080.7 40369.8 42071.6 42429.4 44357.8 44775.3 59719.0 60004.7 60116.7 60660.4 61094.4 7339.8 7853.3 8652.3 8779.5 9037.8 12211.0 12539.3 12574.9 12757.6 13153.9 10877.9 11253.3 11275.4 11625.7 11888.7 11159.0 11249.7 11372.0 11458.7 11630.8 11289.5 11342.1 11413.9 11471.3 11472.8 8202.1 8210.0 8216.3 8259.7 8453.3 8537.4 10332.9 10948.4 11305.0 11446.3 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#3] mootools.html Cycles:180 Average:6128.57 Median:2456.66 stddev:3542.28 (144.2%) stddev-sans-first:3534.64 22:46:09 INFO - PROCESS | 16608 | Values: 1433.2 1567.4 1589.5 1761.8 1881.1 8760.5 9148.7 9162.2 9435.4 9448.8 9166.8 9347.0 9386.1 9395.9 9404.2 2099.6 2363.3 2429.5 2458.5 2501.9 2442.9 2462.9 2464.0 2470.1 2473.6 2490.2 2514.6 2515.9 2519.7 2520.5 2082.2 2407.7 2441.8 2468.3 2476.2 2403.1 2408.1 2415.7 2427.9 2462.9 9372.2 9395.3 9428.3 9445.6 9449.4 5435.9 5579.9 5582.0 5584.2 5593.3 3614.3 3664.1 3684.4 3702.9 3713.7 1166.2 1226.9 1233.5 1248.5 1275.8 13504.2 13507.0 13517.2 13521.7 13531.3 4959.4 5057.3 5060.4 5068.5 5141.9 10983.4 10995.9 11050.0 11055.2 11055.6 4885.4 5374.5 5462.5 5462.7 5492.1 5056.9 5226.0 5229.4 5279.8 5285.1 4242.9 4257.9 4301.4 4465.8 4474.2 1328.1 1332.8 1333.7 1343.4 1344.3 1020.1 1022.9 1024.6 1036.0 1044.1 6188.1 6217.9 6224.3 6252.9 6440.3 434.1 441.7 447.5 449.6 452.5 4158.0 4311.8 4334.6 4357.9 4397.5 11236.3 11566.7 11571.2 11573.4 11640.6 11376.3 11456.4 11470.7 11693.6 11741.2 8552.4 8563.2 8641.5 8716.2 8731.3 6215.1 6329.0 6365.3 6383.3 6435.0 10607.2 10617.4 10717.8 10765.8 10833.9 10890.8 10919.2 11071.7 11072.8 11200.2 7230.7 7361.7 7597.1 7845.5 7928.3 9015.1 9074.0 9084.4 9137.3 9182.8 7683.0 7869.6 7952.8 8004.9 8072.6 7692.6 7770.8 7816.3 7888.5 8119.8 7568.4 7577.5 7629.8 7643.4 7676.3 5334.4 5378.2 5388.5 5469.4 5484.4 5092.0 5248.3 5251.4 5298.1 5367.7 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#4] prototype.html Cycles:180 Average:4679.61 Median:5387.00 stddev:1366.43 (25.4%) stddev-sans-first:1330.63 22:46:09 INFO - PROCESS | 16608 | Values: 327.2 339.9 345.9 351.2 353.8 5330.9 5349.7 5360.0 5372.2 5398.8 5303.5 5338.2 5345.4 5352.3 5372.2 3915.4 4027.8 4133.6 4154.3 4191.1 4104.5 4126.5 4140.1 4150.8 4183.5 3917.5 4141.5 4142.4 4165.5 4165.9 3922.6 4030.5 4037.9 4133.6 4148.8 3631.9 3639.5 3654.4 3655.7 3659.9 5131.5 5152.3 5172.4 5180.3 5190.9 4616.3 4714.4 4731.4 4773.9 4791.4 5462.2 5515.7 5522.3 5533.8 5565.9 4067.7 4068.0 4083.0 4097.2 4111.0 5665.8 5735.3 5740.7 5742.6 5743.4 4040.0 4066.8 4071.6 4088.0 4100.1 4171.5 4178.6 4188.2 4188.6 4191.4 4549.1 4683.5 4688.5 4694.0 4767.9 5503.8 5517.9 5534.0 5537.4 5551.4 1417.5 1422.8 1424.2 1435.9 1445.1 930.4 956.5 959.6 999.1 999.7 5377.3 5393.3 5402.1 5417.5 5440.2 5714.3 5724.8 5744.6 5779.0 5791.0 5208.7 5316.5 5341.2 5383.3 5391.7 2995.4 2997.0 3019.1 3021.2 3032.3 5600.6 5795.3 5818.7 5830.7 5874.6 5685.8 5687.5 5723.5 5759.5 5817.7 5778.5 5780.6 5830.9 5870.3 5875.6 5609.0 5636.4 5721.1 5735.4 5743.7 5546.0 5619.3 5621.5 5658.6 5688.4 5636.4 5842.5 5852.8 5861.9 5901.6 4078.4 4084.8 4087.0 4090.1 4112.3 5406.6 5591.9 5636.6 5649.4 5691.2 5390.7 5468.0 5473.4 5497.0 5569.9 5533.9 5551.3 5553.9 5554.4 5565.6 5323.7 5496.5 5521.3 5553.4 5567.4 5641.5 5643.5 5646.4 5658.2 5674.6 5221.1 5463.6 5511.2 5542.2 5554.9 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - PROCESS | 16608 | [#5] yui.html Cycles:180 Average:16106.42 Median:26169.68 stddev:18944.59 (72.4%) stddev-sans-first:18962.55 22:46:09 INFO - PROCESS | 16608 | Values: 729.6 734.4 756.7 763.8 802.2 14829.9 15293.6 15521.8 15591.3 15602.5 14209.2 14373.1 14416.1 14423.1 14500.7 823.4 830.4 832.0 835.1 837.8 693.5 806.6 835.4 835.5 836.7 879.8 881.5 881.7 881.9 881.9 723.8 843.3 876.9 877.5 878.7 632.7 633.6 634.1 635.7 636.2 14845.0 14867.0 14873.3 14893.7 14921.5 13829.5 14275.3 14278.3 14343.0 14509.4 11485.2 11729.8 13081.7 13378.9 13404.7 4596.1 4631.1 4634.6 4657.1 4663.9 52868.5 58124.0 59828.0 60308.4 60415.4 11272.8 11363.8 11573.8 11656.5 11940.8 20110.3 20165.5 20178.1 20199.4 20249.7 8534.6 8564.7 8677.8 8746.0 8915.5 4961.7 5013.7 5022.2 5033.7 5154.0 3513.7 3609.7 3611.2 3618.1 3620.6 665.5 692.5 724.8 731.3 732.2 591.9 718.2 742.2 813.7 828.4 9932.0 10304.4 10400.2 10459.7 10489.1 601.5 615.5 725.4 773.4 818.7 4580.9 4860.4 4900.3 4950.7 4961.9 69501.0 69790.0 70444.3 70560.0 73173.6 47479.0 52549.2 59144.4 63994.4 65309.3 54930.0 55163.7 55479.0 56300.0 56682.0 9414.6 9543.7 9568.9 9583.5 9616.6 37694.0 38501.8 38661.4 38928.2 39618.4 49525.0 52174.5 52295.7 52526.7 53830.7 11920.3 11992.6 12212.5 12228.3 12357.9 13292.7 13439.7 13441.0 13595.8 13892.6 11727.8 11841.0 12700.7 12873.6 13375.4 13556.6 14061.8 14141.6 14366.0 14531.2 13931.8 13984.0 14099.6 14135.4 14285.4 11454.7 11562.2 11627.2 11942.9 11979.2 11426.3 11703.3 11777.9 11905.2 11946.9 22:46:09 INFO - PROCESS | 16608 | -------- Summary: end -------- 22:46:09 INFO - PROCESS | 16608 | 22:46:09 INFO - TEST-INFO | 16608: exit 0 22:46:09 INFO - TEST-OK | dromaeo_css | took 1112503ms 22:46:09 INFO - TEST-START | kraken 22:46:09 INFO - operating with platform_type : linux_ 22:46:09 INFO - Initialising browser for kraken test... 22:46:09 INFO - TEST-INFO | started process 16791 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK7T9gq/profile http://localhost:59953/getInfo.html) 22:46:13 INFO - PROCESS | 16791 | __metrics Screen width/height:1600/1200 22:46:13 INFO - PROCESS | 16791 | colorDepth:24 22:46:13 INFO - PROCESS | 16791 | Browser inner width/height: 1024/697 22:46:13 INFO - PROCESS | 16791 | __metrics 22:46:14 ERROR - PROCESS | 16791 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:46:15 INFO - TEST-INFO | 16791: exit 0 22:46:15 INFO - Browser initialized. 22:46:15 INFO - Running cycle 1/1 for kraken test... 22:46:15 INFO - TEST-INFO | started process 16872 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK7T9gq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:27 INFO - PROCESS | 16872 | RSS: Main: 152690688 22:46:27 INFO - PROCESS | 16872 | 22:46:30 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59953/tests/kraken/driver.html?testName=audio-beat-detection) 22:46:30 INFO - PROCESS | 16872 | RSS: Main: 209244160 22:46:30 INFO - PROCESS | 16872 | 22:46:33 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59953/tests/kraken/driver.html?testName=audio-dft) 22:46:34 INFO - PROCESS | 16872 | RSS: Main: 197533696 22:46:34 INFO - PROCESS | 16872 | 22:46:37 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59953/tests/kraken/driver.html?testName=audio-fft) 22:46:37 INFO - PROCESS | 16872 | RSS: Main: 265293824 22:46:37 INFO - PROCESS | 16872 | 22:46:40 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59953/tests/kraken/driver.html?testName=audio-oscillator) 22:46:40 INFO - PROCESS | 16872 | RSS: Main: 174620672 22:46:40 INFO - PROCESS | 16872 | 22:46:43 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59953/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:46:43 INFO - PROCESS | 16872 | RSS: Main: 178778112 22:46:43 INFO - PROCESS | 16872 | 22:46:46 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59953/tests/kraken/driver.html?testName=imaging-darkroom) 22:46:47 INFO - PROCESS | 16872 | RSS: Main: 214835200 22:46:47 INFO - PROCESS | 16872 | 22:46:50 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59953/tests/kraken/driver.html?testName=imaging-desaturate) 22:46:51 INFO - PROCESS | 16872 | RSS: Main: 213057536 22:46:51 INFO - PROCESS | 16872 | 22:46:54 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59953/tests/kraken/driver.html?testName=json-parse-financial) 22:46:55 INFO - PROCESS | 16872 | RSS: Main: 214089728 22:46:55 INFO - PROCESS | 16872 | 22:46:56 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59953/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:46:57 INFO - PROCESS | 16872 | RSS: Main: 202014720 22:46:57 INFO - PROCESS | 16872 | 22:46:59 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:46:59 INFO - PROCESS | 16872 | RSS: Main: 206381056 22:46:59 INFO - PROCESS | 16872 | 22:47:01 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:47:02 INFO - PROCESS | 16872 | RSS: Main: 217239552 22:47:02 INFO - PROCESS | 16872 | 22:47:05 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:47:05 INFO - PROCESS | 16872 | RSS: Main: 224124928 22:47:05 INFO - PROCESS | 16872 | 22:47:08 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:47:09 INFO - PROCESS | 16872 | RSS: Main: 219803648 22:47:09 INFO - PROCESS | 16872 | 22:47:10 INFO - PROCESS | 16872 | Cycle 1(1): loaded http://localhost:59953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59953/tests/kraken/driver.html?testName=ai-astar) 22:47:11 INFO - PROCESS | 16872 | RSS: Main: 266371072 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | __start_tp_report 22:47:11 INFO - PROCESS | 16872 | _x_x_mozilla_page_load 22:47:11 INFO - PROCESS | 16872 | _x_x_mozilla_page_load_details 22:47:11 INFO - PROCESS | 16872 | |i|pagename|runs| 22:47:11 INFO - PROCESS | 16872 | |0;ai-astar;110;105;110;109;115;111;109;109;109;109 22:47:11 INFO - PROCESS | 16872 | |1;audio-beat-detection;148;124;149;207;126;148;151;127;182;125 22:47:11 INFO - PROCESS | 16872 | |2;audio-dft;161;160;160;159;161;160;161;162;162;161 22:47:11 INFO - PROCESS | 16872 | |3;audio-fft;74;74;76;142;74;73;74;74;132;73 22:47:11 INFO - PROCESS | 16872 | |4;audio-oscillator;91;92;148;93;96;93;116;92;91;123 22:47:11 INFO - PROCESS | 16872 | |5;imaging-gaussian-blur;116;116;116;117;116;116;117;117;117;117 22:47:11 INFO - PROCESS | 16872 | |6;imaging-darkroom;145;145;145;145;145;147;145;145;146;145 22:47:11 INFO - PROCESS | 16872 | |7;imaging-desaturate;95;94;94;95;95;95;95;95;94;94 22:47:11 INFO - PROCESS | 16872 | |8;json-parse-financial;54;54;55;54;54;54;58;106;51;53 22:47:11 INFO - PROCESS | 16872 | |9;json-stringify-tinderbox;58;59;58;59;59;64;59;59;59;60 22:47:11 INFO - PROCESS | 16872 | |10;stanford-crypto-aes;82;80;78;77;84;81;80;79;89;80 22:47:11 INFO - PROCESS | 16872 | |11;stanford-crypto-ccm;123;117;131;125;123;130;118;120;186;122 22:47:11 INFO - PROCESS | 16872 | |12;stanford-crypto-pbkdf2;163;159;160;164;160;161;158;158;160;157 22:47:11 INFO - PROCESS | 16872 | |13;stanford-crypto-sha256-iterative;60;63;62;62;60;61;62;61;63;61 22:47:11 INFO - PROCESS | 16872 | __end_tp_report 22:47:11 INFO - PROCESS | 16872 | __start_cc_report 22:47:11 INFO - PROCESS | 16872 | _x_x_mozilla_cycle_collect,1861 22:47:11 INFO - PROCESS | 16872 | __end_cc_report 22:47:11 INFO - PROCESS | 16872 | __startTimestamp1463550431025__endTimestamp 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | ------- Summary: start ------- 22:47:11 INFO - PROCESS | 16872 | Number of tests: 14 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#0] ai-astar Cycles:10 Average:109.60 Median:109.50 stddev:2.46 (2.2%) stddev-sans-first:2.60 22:47:11 INFO - PROCESS | 16872 | Values: 110.0 105.0 110.0 109.0 115.0 111.0 109.0 109.0 109.0 109.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#1] audio-beat-detection Cycles:10 Average:148.70 Median:148.50 stddev:27.23 (18.3%) stddev-sans-first:28.88 22:47:11 INFO - PROCESS | 16872 | Values: 148.0 124.0 149.0 207.0 126.0 148.0 151.0 127.0 182.0 125.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 22:47:11 INFO - PROCESS | 16872 | Values: 161.0 160.0 160.0 159.0 161.0 160.0 161.0 162.0 162.0 161.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#3] audio-fft Cycles:10 Average:86.60 Median:74.00 stddev:26.68 (36.1%) stddev-sans-first:27.91 22:47:11 INFO - PROCESS | 16872 | Values: 74.0 74.0 76.0 142.0 74.0 73.0 74.0 74.0 132.0 73.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#4] audio-oscillator Cycles:10 Average:103.50 Median:92.00 stddev:19.35 (21.0%) stddev-sans-first:19.99 22:47:11 INFO - PROCESS | 16872 | Values: 91.0 92.0 148.0 93.0 96.0 93.0 116.0 92.0 91.0 123.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:47:11 INFO - PROCESS | 16872 | Values: 116.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 22:47:11 INFO - PROCESS | 16872 | Values: 145.0 145.0 145.0 145.0 145.0 147.0 145.0 145.0 146.0 145.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:47:11 INFO - PROCESS | 16872 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:54.00 stddev:16.50 (30.6%) stddev-sans-first:17.39 22:47:11 INFO - PROCESS | 16872 | Values: 54.0 54.0 55.0 54.0 54.0 54.0 58.0 106.0 51.0 53.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:1.71 (2.9%) stddev-sans-first:1.74 22:47:11 INFO - PROCESS | 16872 | Values: 58.0 59.0 58.0 59.0 59.0 64.0 59.0 59.0 59.0 60.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:80.50 stddev:3.43 (4.3%) stddev-sans-first:3.62 22:47:11 INFO - PROCESS | 16872 | Values: 82.0 80.0 78.0 77.0 84.0 81.0 80.0 79.0 89.0 80.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:124.00 stddev:20.37 (16.4%) stddev-sans-first:21.47 22:47:11 INFO - PROCESS | 16872 | Values: 123.0 117.0 131.0 125.0 123.0 130.0 118.0 120.0 186.0 122.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:160.00 stddev:2.21 (1.4%) stddev-sans-first:2.06 22:47:11 INFO - PROCESS | 16872 | Values: 163.0 159.0 160.0 164.0 160.0 161.0 158.0 158.0 160.0 157.0 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - PROCESS | 16872 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.08 (1.7%) stddev-sans-first:1.00 22:47:11 INFO - PROCESS | 16872 | Values: 60.0 63.0 62.0 62.0 60.0 61.0 62.0 61.0 63.0 61.0 22:47:11 INFO - PROCESS | 16872 | -------- Summary: end -------- 22:47:11 INFO - PROCESS | 16872 | 22:47:11 INFO - TEST-INFO | 16872: exit 0 22:47:11 INFO - TEST-OK | kraken | took 62159ms 22:47:11 INFO - SUITE-END | took 1174s 22:47:12 INFO - Completed test suite (00:19:34) 22:47:12 INFO - javascript benchmark 22:47:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.065420700893, 3169.9524507132382, 3181.2524056846646, 3210.7270881975032, 3236.0291912426264, 5328.706921119337, 5394.973025134869, 5501.257330260413, 5624.15637654352, 5628.324601047875, 5485.396606373296, 5543.390227075019, 5545.251391062206, 5559.916601250974, 5574.804881829137, 4982.177940639799, 5036.6726162799305, 5084.237364395333, 5098.668586541863, 5234.084035293828, 5125.2055931330515, 5153.6392452528335, 5187.429382767892, 5205.245239440298, 5220.4779522047875, 4797.328374027639, 4922.3354847095725, 4947.6784009039475, 4971.130052240879, 5026.8240611578785, 4996.825111121093, 5008.574271186953, 5084.720340381277, 5087.033463641897, 5136.897262054776, 4831.516848315176, 5048.823291184791, 5051.292819005343, 5072.492750724898, 5072.9746351268, 5391.649542779746, 5469.945300546983, 5474.09677403228, 5522.641028333145, 5561.110222364443, 5956.642601443928, 5990.0, 5992.041273396279, 6010.248718910137, 6015.939840601582, 5732.0255556555485, 5853.88292234153, 5922.58541902067, 5954.225950626391, 5980.940190598082, 5541.9168712469345, 5639.746211420496, 5648.491635752761, 5651.463111004448, 5652.689102099424, 6131.193168498116, 6132.938670613326, 6137.693115344215, 6151.108089327023, 6159.6612186330185, 5558.055130627803, 5775.913361299584, 5834.591578589543, 5939.851503712355, 5956.344802071769, 5957.434043765836, 5980.13288073227, 6001.219841420588, 6015.699215039318, 6018.067199584071, 5881.32364778056, 5924.763009479659, 5926.703664816828, 5933.762649494059, 5939.2575928009, 5636.4927156556105, 5800.129980502874, 5876.970615146897, 5894.2926848778425, 5933.970330148321, 4431.069475410135, 4431.1137772445, 4431.645468362523, 4433.069055498381, 4463.687541872103, 3924.1955399143108, 3938.4683067786054, 3950.6839452843706, 3992.6207010333974, 4022.0, 5349.384820745664, 5387.8114266001285, 5477.8356649299785, 5580.553555715533, 5583.804566840141, 5953.077273022688, 5982.282126144891, 5990.880182396328, 5995.400459953969, 5998.1002849572915, 5602.047651899187, 5612.915806262909, 5616.269884914935, 5619.157126430986, 5623.8594035149445, 4933.407991041098, 5009.499050095034, 5072.264521644414, 5100.948990510122, 5121.795128194831, 6175.96912015437, 6184.5052395808225, 6195.597286176384, 6198.349173336825, 6204.658743769136, 5580.944190558205, 5969.134475500941, 5989.850253743692, 5991.191189189403, 5994.460498554977, 6113.749968913837, 6192.102145189013, 6198.659073750807, 6295.716692748746, 6310.7791227306825, 5927.842746951978, 5964.522838173022, 5965.2245208124405, 5974.880502389841, 6032.245969253843, 5734.455226753452, 5884.705764711833, 6015.909761353671, 6063.393660633901, 6082.7262773174425, 5726.828195154152, 5864.706764661664, 5873.647581145145, 5879.382664820132, 5928.258967629046, 5841.561882858888, 5849.093390524476, 5849.122631605123, 5852.502537284378, 5855.03391940325, 5499.945000549943, 5613.101903695389, 5687.658740475585, 5745.770169193185, 5761.452661997104, 5862.94137058624, 5876.324222714443, 5911.527077833849, 5917.615355001911, 5920.851978700567, 5718.942810571841, 5725.456081672234, 5725.627834190765, 5734.655920644775, 5762.423757624204], "value": 5468.648918015464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7127614383165, 638.5944924972642, 640.7725257533573, 653.6796969484951, 655.6197405504807, 36320.0, 37013.25973480529, 37082.0, 37125.44311835324, 37163.81418092913, 45816.54183458164, 46648.53351466484, 46975.29537056947, 49997.50002499974, 50005.749971250094, 8947.970983336902, 9125.634974601007, 9125.771855703593, 9126.178643922045, 9130.72607821763, 8893.68872089477, 9019.413738107034, 9021.323400744937, 9021.503817290046, 9022.45865248084, 9089.590968406404, 9132.771680707969, 9135.086491350845, 9138.725838224864, 9155.86266206004, 9064.77338066547, 9089.500077495735, 9136.908630913738, 9137.5888085036, 9141.085891410872, 8982.416142950753, 9076.092390760938, 9086.727398178065, 9092.363534552584, 9095.681651142178, 42412.363814542805, 42523.362149567474, 42811.35782963258, 42824.57175428237, 42847.785761071, 3870.438786375988, 4306.80619372129, 4386.298192289219, 4487.12501062293, 4498.775061246924, 32256.516152257736, 32911.34177316464, 33036.33927321464, 33483.49774753356, 33598.83200584006, 4362.541933097011, 4363.563643635643, 4488.192125417424, 4501.23479008566, 4721.740304283263, 349337.0, 350615.99999999744, 350939.0, 351722.0, 351930.0, 36231.4565281521, 59550.70224648849, 59807.0, 60204.0, 60258.0, 36670.0, 38743.2251354977, 38837.6116238834, 38853.0, 38858.2228355437, 15595.0, 15713.528594142193, 15723.292451839694, 15740.842591574166, 15741.527754167393, 13352.732945341038, 13449.0, 13454.394552245172, 13456.730865382637, 13461.86538134606, 8762.780930476789, 8765.956170219235, 8778.91221087781, 8822.161894620001, 8838.734837954871, 3357.8992630221132, 3359.428897087501, 3415.7438192135687, 3444.4144495435835, 3469.965300347015, 1040.7086015915404, 1100.5597760895546, 1146.8107762219308, 1151.9712007199719, 1154.976900462003, 33168.50247246293, 33621.49567756485, 33826.49260261098, 33871.49192762111, 34130.84966800547, 1116.625930313338, 1134.4384529657873, 1158.44394690549, 1175.6649354933975, 1246.3269834289563, 17467.126643667765, 17572.648547028988, 17649.294028238983, 17708.0, 17743.290268389377, 583079.0, 585079.0, 589549.0, 589948.0, 590108.0, 59574.0, 59938.40061599416, 60031.69984150139, 60074.699626501584, 60141.39858601358, 1291.9870801292054, 1395.8743713065874, 1420.7584710599224, 1536.500637292862, 1650.4471002214393, 31035.982661126953, 33609.3278134431, 35720.64279357173, 35814.0, 37614.0, 57714.134287984874, 58444.12333814911, 58447.0, 58641.0, 58667.70666146642, 496777.0, 502500.0, 502926.0, 503523.0, 504002.0, 44926.3261051091, 45822.31266530938, 46721.29918051164, 46735.76632116954, 46749.76625116853, 46021.0, 50283.49716502788, 50528.24207636815, 50719.0, 51874.22188667249, 41769.0, 42387.152256955305, 42815.78592107019, 43595.56404435915, 43819.342709860015, 41408.1718365625, 42828.78585607052, 42858.357124642534, 42903.785481072395, 43072.56927430686, 42017.78991105147, 42425.36361954511, 43241.13517729691, 43287.783561082, 43669.56330436655, 37472.0, 37643.43534846925, 38005.80997095107, 38462.6153738459, 38574.42138367983, 29158.27104322409, 29175.270618234714, 29410.55884161782, 29516.0, 29574.556381653863], "value": 20038.93027402934, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.95138107736534, 391.9666828319593, 395.9564447910731, 396.0771402631868, 413.86135644559073, 17729.7340539892, 18494.815051849477, 19186.136623851926, 19204.71192932107, 19626.607467850634, 18290.359837405693, 18544.629107417844, 18761.7185742214, 19311.420657380266, 19346.709799352986, 5537.833864984057, 5615.073512870372, 5752.137179423099, 5754.74103665336, 5764.769409223626, 5568.805091821787, 5617.691026993513, 5642.605017648766, 5649.717514124277, 5650.858728531799, 5438.265834112403, 5586.91619625706, 5641.238432811559, 5659.717014149276, 5685.602007859451, 5643.492085712285, 5648.3222013358245, 5649.265595472562, 5656.830295091175, 5692.686902220376, 4888.462269150391, 5146.742662866842, 5152.793888244466, 5153.2270159476, 5156.587473002151, 22246.77753222463, 22495.21266755672, 22808.77191228083, 22908.0, 22972.885135574215, 2426.259990702835, 2427.041318679112, 2428.5992811186134, 2450.093465417789, 2547.8853451594723, 14239.430422783076, 14447.566573002721, 14659.853401466062, 14708.0, 15078.321475533518, 1969.3402710092137, 2003.378952524722, 2012.4264584593468, 2048.9077991490417, 2084.322595156565, 71549.64225178893, 73950.26049739488, 75179.62410187913, 75500.24499755041, 77724.0, 10789.352638841694, 11185.49665265065, 11258.0, 11333.356663283777, 11580.710482238012, 15879.364825407085, 15893.364265429253, 16021.359145634277, 16042.43851465193, 16044.358225671076, 8356.540390278593, 8454.492730436194, 8508.61711222984, 8623.845908946236, 8659.567021648892, 8093.3929955252415, 8175.182481751777, 8313.875291870627, 8336.416450848503, 8352.749417517483, 4869.513048695102, 4871.464138944713, 4905.141600219948, 4974.427940786783, 5041.521055499722, 2002.8097330753847, 2050.682144267611, 2063.3912995666315, 2070.9482262943247, 2100.915963361479, 987.9555420006117, 1019.5961404555825, 1100.1254003067581, 1119.8656161260537, 1171.824226366035, 17612.471625851245, 19042.42872713821, 19846.503837403892, 19908.70136947947, 19911.502212444517, 1015.7765291635828, 1045.7856139491387, 1063.547992103353, 1073.2648136026846, 1138.9316641001567, 10297.227707921937, 11149.609763658233, 11217.831732524019, 11229.606963756229, 11241.494132764044, 476537.0, 477578.0595765035, 485208.0, 485638.0, 493662.0, 72813.63593182, 75862.62068689731, 96643.0, 98465.50767246261, 104292.47853760683, 1035.5236591167995, 1076.1444651502075, 1097.7530055737395, 1110.3171549497163, 1112.449337577904, 17708.114594270493, 17892.642147157243, 18016.729749054037, 18016.90991545034, 18080.72878906791, 40369.798151009054, 42071.57928420677, 42429.36355954725, 44357.778211108736, 44775.328370073825, 59719.0, 60004.69997649984, 60116.69941650264, 60660.393396065476, 61094.38905610887, 7339.77980660581, 7853.253940875607, 8652.307815374881, 8779.463593871174, 9037.77405564866, 12211.023118150704, 12539.310337931502, 12574.87425125737, 12757.55348562833, 13153.868461315264, 10877.891221087688, 11253.324800511995, 11275.379854108103, 11625.6512304631, 11888.702782430162, 11159.0, 11249.71875703114, 11372.0, 11458.656240312805, 11630.76738465209, 11289.548418063514, 11342.092632589209, 11413.942930285295, 11471.254368466023, 11472.770544589228, 8202.138775428493, 8209.958950205211, 8216.301614362606, 8259.669613215645, 8453.281471075028, 8537.359698022798, 10332.896671033193, 10948.39783811866, 11305.0, 11446.255993360404], "value": 9334.271810303184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.1902475101574, 1567.3573834727763, 1589.5390336802327, 1761.832625900539, 1881.0970734047658, 8760.474371537704, 9148.725538233846, 9162.221211197048, 9435.433873967566, 9448.763780905481, 9166.816663666723, 9346.953265233682, 9386.108319709634, 9395.906040939588, 9404.247660187186, 2099.632564301241, 2363.3028256664325, 2429.538387706339, 2458.4960083182905, 2501.9374515637164, 2442.9389265268333, 2462.938426539342, 2464.039024780337, 2470.1354525915967, 2473.6413220083077, 2490.1657944588583, 2514.6479492871013, 2515.8993640254366, 2519.6976362836394, 2520.5085008423275, 2082.187946700788, 2407.6629271901948, 2441.7924476419525, 2468.2968751561366, 2476.232367965936, 2403.134871446278, 2408.0608562660577, 2415.7221919479307, 2427.9393015174587, 2462.876856157203, 9372.203362714177, 9395.34232603718, 9428.292878034175, 9445.574949127236, 9449.433034017911, 5435.891282174334, 5579.944200557983, 5581.972090139564, 5584.162375643646, 5593.32880054392, 3614.34941710492, 3664.1022949377575, 3684.373656478405, 3702.8703995360033, 3713.6843368313725, 1166.1778446195412, 1226.926384416938, 1233.5004323249098, 1248.4756402311052, 1275.8086287056924, 13504.189748615114, 13506.986975976939, 13517.188968661912, 13521.661958451019, 13531.323433828367, 4959.429665588431, 5057.342545469065, 5060.418051924003, 5068.493150684976, 5141.891824352073, 10983.39591322469, 10995.890041099487, 11050.010998020434, 11055.226134170693, 11055.557777688964, 4885.389326334208, 5374.543163831118, 5462.535684466865, 5462.7268636568015, 5492.148716948879, 5056.898862022739, 5225.9738701307015, 5229.424763276036, 5279.8152064678325, 5285.127953887565, 4242.896846819849, 4257.936130958038, 4301.354796780508, 4465.799039043251, 4474.172278128557, 1328.0570794735652, 1332.8267325247655, 1333.6999175185506, 1343.4156142078227, 1344.3076815440056, 1020.1430798129459, 1022.8772547294373, 1024.5952848624752, 1035.994820025895, 1044.0968562193648, 6188.071789231652, 6217.937820621826, 6224.284207316216, 6252.874942501216, 6440.25937017249, 434.06459080681185, 441.65771527066136, 447.5189171640499, 449.64028776978284, 452.4751288505318, 4158.0, 4311.754616888053, 4334.612923864333, 4357.934630980538, 4397.516273209944, 11236.269642473213, 11566.65300041, 11571.190016698918, 11573.363465009337, 11640.592579259684, 11376.317420954603, 11456.427178641201, 11470.680871699695, 11693.590724324607, 11741.236819606698, 8552.444091134057, 8563.209508472935, 8641.524716140548, 8716.215540601377, 8731.345149113715, 6215.06773983888, 6328.968355158195, 6365.3316401779, 6383.297837237993, 6434.967825161031, 10607.20445966571, 10617.41604211745, 10717.785644287227, 10765.838512422164, 10833.891661083288, 10890.836637450286, 10919.181061420584, 11071.723206919569, 11072.778544429228, 11200.159988001096, 7230.74692385764, 7361.705531778883, 7597.088349398107, 7845.450818442654, 7928.286454218917, 9015.14356136166, 9074.0, 9084.40951338161, 9137.314701397288, 9182.816343672956, 7682.961585192038, 7869.6458659361615, 7952.84094318122, 8004.91995080065, 8072.556009419538, 7692.615369231628, 7770.844583108194, 7816.257455541714, 7888.526688398714, 8119.75640730779, 7568.4323675723435, 7577.507462014952, 7629.847403052019, 7643.350315223268, 7676.270754278335, 5334.439883812299, 5378.247045413567, 5388.54197393226, 5469.3983661797965, 5484.396716361117, 5092.0, 5248.343957005374, 5251.36983561975, 5298.099323115002, 5367.651102678314], "value": 4803.157951223425, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.1624640919247, 339.93031428557146, 345.8772135891758, 351.2325568632538, 353.77712041413906, 5330.866728331784, 5349.679019258847, 5359.999999999995, 5372.247885296062, 5398.811041613544, 5303.549198318147, 5338.199270109486, 5345.412004679482, 5352.2506849041065, 5372.2210279509545, 3915.37354023357, 4027.758334499925, 4133.627973482386, 4154.335306350985, 4191.140816132701, 4104.548499665035, 4126.46355973723, 4140.136938377742, 4150.8132134053885, 4183.477065366829, 3917.5494818096, 4141.482314710661, 4142.378643203513, 4165.520965089023, 4165.937510937339, 3922.5881282465216, 4030.536488303824, 4037.899052523696, 4133.607307305801, 4148.771817550033, 3631.89104326868, 3639.526861507987, 3654.3604869147794, 3655.7258205634685, 3659.9268014639824, 5131.512506311932, 5152.304438900738, 5172.431032586413, 5180.274761533388, 5190.92213616796, 4616.307553866914, 4714.387129673155, 4731.408573928259, 4773.856784296442, 4791.4250289965075, 5462.153366228241, 5515.696636684981, 5522.30971128609, 5533.778648854041, 5565.858998905214, 4067.735597186203, 4067.97966010171, 4083.0, 4097.180563887205, 4111.033907031845, 5665.830025099212, 5735.340435849889, 5740.684262365568, 5742.626729262584, 5743.425657434265, 4040.0, 4066.8169932353117, 4071.592840715964, 4088.0, 4100.077482566398, 4171.478565179353, 4178.641941369006, 4188.183304255702, 4188.602082802129, 4191.44321963608, 4549.067441174551, 4683.501279590499, 4688.46082700487, 4693.967327188013, 4767.856964291007, 5503.816679413865, 5517.868836888509, 5533.983978731601, 5537.446255374511, 5551.417101204396, 1417.4684493315008, 1422.7581311177125, 1424.223798030076, 1435.9210243436712, 1445.1184777285848, 930.3812964378748, 956.516958935733, 959.6497278493439, 999.1257649556638, 999.7100840756099, 5377.279270633359, 5393.325834270716, 5402.109261758354, 5417.512423881791, 5440.183972404092, 5714.285714285715, 5724.799632012943, 5744.569157313218, 5779.0, 5791.0, 5208.661437006583, 5316.521513063843, 5341.172118321666, 5383.300170977748, 5391.652086978255, 2995.445842519141, 2996.9700302997126, 3019.0942717184757, 3021.214484234115, 3032.348045170299, 5600.607957443021, 5795.290389335156, 5818.679972601548, 5830.746389526273, 5874.591417518796, 5685.800996965087, 5687.488126068673, 5723.456271654186, 5759.481646651738, 5817.720101577646, 5778.497590626467, 5780.5953583248765, 5830.8542286442325, 5870.295564532284, 5875.588708790344, 5609.046462101534, 5636.436356364331, 5721.141828725725, 5735.3977832327, 5743.741531631003, 5546.001719690333, 5619.269494965628, 5621.498433197352, 5658.603897727119, 5688.431156884444, 5636.351819540641, 5842.474177324061, 5852.7658893645485, 5861.856937897069, 5901.645901245939, 4078.4494093297026, 4084.7541499842832, 4086.959130408658, 4090.1001779608437, 4112.259793237246, 5406.621536492407, 5591.909577632323, 5636.605437619327, 5649.435056494318, 5691.23168372281, 5390.703511306916, 5468.0, 5473.425290344457, 5497.038018346693, 5569.944300557105, 5533.944660553343, 5551.278333816578, 5553.861458400937, 5554.444555544414, 5565.6104072714525, 5323.707196104251, 5496.5053145217125, 5521.282233309644, 5553.361363443094, 5567.4432556744005, 5641.492265696107, 5643.52030077448, 5646.435356464485, 5658.17956396312, 5674.57440691958, 5221.086309895829, 5463.644863083887, 5511.22842802, 5542.224088627515, 5554.861128471821], "value": 4248.550360467468, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.6388287797538, 734.3721118443731, 756.6746299091388, 763.8472305538897, 802.2418814220562, 14829.925850370746, 15293.617659558517, 15521.767173492379, 15591.298391572363, 15602.531924042269, 14209.218492982882, 14373.137611743277, 14416.135031898068, 14423.134611923266, 14500.709985800278, 823.3618945317397, 830.3855147191065, 832.0182185021672, 835.1356346181703, 837.8240569480416, 693.5076095971841, 806.6410447350931, 835.3859912963967, 835.4694768821812, 836.7406104107715, 879.8020445399798, 881.4755220643707, 881.7442941546943, 881.9250363719092, 881.9294456443502, 723.8226634474587, 843.2832092721138, 876.8991565969932, 877.5349064995562, 878.7100256915203, 632.6646877155115, 633.5628416392674, 634.1344065350809, 635.7075745157233, 636.1634450697318, 14845.0, 14867.03364281329, 14873.33070011852, 14893.70212595742, 14921.477748278758, 13829.51596694121, 14275.286235688174, 14278.286085695674, 14343.0, 14509.419623215059, 11485.196036277464, 11729.824052639216, 13081.672958176026, 13378.93310533441, 13404.731905361936, 4596.103759766846, 4631.073785242931, 4634.629229661619, 4657.138429390574, 4663.88340291492, 52868.471315287126, 58124.0, 59828.0, 60308.39691603115, 60415.395846041414, 11272.830907536394, 11363.772724545463, 11573.826392604118, 11656.53373865036, 11940.820887686692, 20110.296139635044, 20165.49586260355, 20178.091985860672, 20199.39401817948, 20249.696254556195, 8534.573271336409, 8564.74305770828, 8677.783055423539, 8746.0, 8915.465072095694, 4961.677490963148, 5013.699178049329, 5022.171341728574, 5033.748312584429, 5153.994970980698, 3513.718902487795, 3609.7473176877893, 3611.2235869287706, 3618.0773902654655, 3620.619834917296, 665.4809248785955, 692.5117791956659, 724.7825652304288, 731.3308322884501, 732.2018999290799, 591.8520369907524, 718.2386670129679, 742.2466196810242, 813.6989313953875, 828.3704384667695, 9932.0, 10304.433256170983, 10400.230520061075, 10459.738506537398, 10489.055984961391, 601.506764453153, 615.4676205082661, 725.3906718356606, 773.377431167904, 818.6520728690402, 4580.908381832344, 4860.36815214027, 4900.338454308694, 4950.702957822542, 4961.925571116436, 69501.0, 69790.0, 70444.29555704378, 70560.0, 73173.634131829, 47479.0, 52549.2117618236, 59144.40855591475, 63994.36005639884, 65309.346906531275, 54930.0, 55163.724181378835, 55479.0, 56300.0, 56682.0, 9414.62341506332, 9543.665971690958, 9568.856467153137, 9583.472908989794, 9616.615335386507, 37694.0, 38501.80749096349, 38661.42007869828, 38928.221435570566, 39618.405723914744, 49525.0, 52174.47825521696, 52295.73852130715, 52526.737366312926, 53830.73084634552, 11920.344381059125, 11992.580259690869, 12212.511499539918, 12228.32744199042, 12357.93821030925, 13292.659373746494, 13439.715248407598, 13441.0, 13595.796063058864, 13892.583222503341, 11727.824082638937, 11841.0, 12700.682482938, 12873.613791585893, 13375.398107085399, 13556.59330220095, 14061.789073164115, 14141.646458838612, 14366.0, 14531.200783956561, 13931.791023134863, 13984.0, 14099.577012689635, 14135.363908624342, 14285.357158927665, 11454.713632159262, 11562.248453850809, 11627.186096973131, 11942.940285298519, 11979.161458697807, 11426.25729327591, 11703.2978021319, 11777.941110294736, 11905.16663833558, 11946.940265298617], "value": 7173.54410799088, "unit": "score"}], "name": "dromaeo_css", "value": 7287.243357038886, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 109.0, 115.0, 111.0, 109.0, 109.0, 109.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 124.0, 149.0, 207.0, 126.0, 148.0, 151.0, 127.0, 182.0, 125.0], "value": 148.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 161.0, 160.0, 161.0, 162.0, 162.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 142.0, 74.0, 73.0, 74.0, 74.0, 132.0, 73.0], "value": 86.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 148.0, 93.0, 96.0, 93.0, 116.0, 92.0, 91.0, 123.0], "value": 103.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 145.0, 146.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 58.0, 106.0, 51.0, 53.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 59.0, 64.0, 59.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 78.0, 77.0, 84.0, 81.0, 80.0, 79.0, 89.0, 80.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 131.0, 125.0, 123.0, 130.0, 118.0, 120.0, 186.0, 122.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 160.0, 164.0, 160.0, 161.0, 158.0, 158.0, 160.0, 157.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 62.0, 62.0, 60.0, 61.0, 62.0, 61.0, 63.0, 61.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1516.2, "alertThreshold": 2.0}]} 22:47:12 INFO - Return code: 0 22:47:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:47:12 ERROR - # TBPL SUCCESS # 22:47:12 INFO - Running post-action listener: _resource_record_post_action 22:47:12 INFO - ##### 22:47:12 INFO - ##### Finished run-tests step (success) 22:47:12 INFO - ##### 22:47:12 INFO - Running post-run listener: _resource_record_post_run 22:47:12 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 537640960; Read time: 192; Write time: 466260 22:47:12 INFO - TinderboxPrint: CPU usage
13.1% 22:47:12 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 192 22:47:12 INFO - TinderboxPrint: I/O write bytes / time
537,640,960 / 466,260 22:47:12 INFO - TinderboxPrint: CPU idle
8,275.6 (86.9%) 22:47:12 INFO - TinderboxPrint: CPU user
1,202.0 (12.6%) 22:47:12 INFO - TinderboxPrint: Swap in / out
0 / 0 22:47:12 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246849536; Read time: 0; Write time: 277148 22:47:12 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 290791424; Read time: 192; Write time: 189112 22:47:12 INFO - Running post-run listener: _upload_blobber_files 22:47:12 INFO - Blob upload gear active. 22:47:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:47:12 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:47:12 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:47:12 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:47:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:47:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:47:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:17 INFO - (blobuploader) - INFO - Done attempting. 22:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:47:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:18 INFO - (blobuploader) - INFO - Done attempting. 22:47:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:47:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:47:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:47:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:47:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:47:19 INFO - (blobuploader) - INFO - Done attempting. 22:47:19 INFO - (blobuploader) - INFO - Iteration through files over. 22:47:19 INFO - Return code: 0 22:47:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:47:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:47:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2a411d09bde98b55aba4441d5d2d0107fa174c99aae1c7bb005db1b6b47087a155a64f774a04d37062a4cc56ca42c7ffd1a5c3d2fa0a16eddb2fa8c6b3b1be26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa8f6f92b6a5ce8b96da8e5e0dfe2c1df700f5e3edf12acd71389a707dab5ee2ea964ee8b9d20768670fdbfc7c61a73cfafb403efb973409f76193a34da1400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6f0b5d153fa71c5c282f774b5a53357d12340cd66a4d39dead7495e3260065548015e66f09136569c8ae38ec2c04d6a507499ff925bf623b2907da9a6100100"} 22:47:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:47:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:47:19 INFO - Contents: 22:47:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2a411d09bde98b55aba4441d5d2d0107fa174c99aae1c7bb005db1b6b47087a155a64f774a04d37062a4cc56ca42c7ffd1a5c3d2fa0a16eddb2fa8c6b3b1be26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa8f6f92b6a5ce8b96da8e5e0dfe2c1df700f5e3edf12acd71389a707dab5ee2ea964ee8b9d20768670fdbfc7c61a73cfafb403efb973409f76193a34da1400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6f0b5d153fa71c5c282f774b5a53357d12340cd66a4d39dead7495e3260065548015e66f09136569c8ae38ec2c04d6a507499ff925bf623b2907da9a6100100"} 22:47:19 INFO - Running post-run listener: copy_logs_to_upload_dir 22:47:19 INFO - Copying logs to upload dir... 22:47:19 INFO - mkdir: /builds/slave/test/build/upload/logs 22:47:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.595554 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-17 22:47:19.187637) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 22:47:19.191129) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 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/2a411d09bde98b55aba4441d5d2d0107fa174c99aae1c7bb005db1b6b47087a155a64f774a04d37062a4cc56ca42c7ffd1a5c3d2fa0a16eddb2fa8c6b3b1be26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa8f6f92b6a5ce8b96da8e5e0dfe2c1df700f5e3edf12acd71389a707dab5ee2ea964ee8b9d20768670fdbfc7c61a73cfafb403efb973409f76193a34da1400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6f0b5d153fa71c5c282f774b5a53357d12340cd66a4d39dead7495e3260065548015e66f09136569c8ae38ec2c04d6a507499ff925bf623b2907da9a6100100"} build_url:https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/YO-3dGR7RY2OnSXr9LXoZw/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/2a411d09bde98b55aba4441d5d2d0107fa174c99aae1c7bb005db1b6b47087a155a64f774a04d37062a4cc56ca42c7ffd1a5c3d2fa0a16eddb2fa8c6b3b1be26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa8f6f92b6a5ce8b96da8e5e0dfe2c1df700f5e3edf12acd71389a707dab5ee2ea964ee8b9d20768670fdbfc7c61a73cfafb403efb973409f76193a34da1400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b6f0b5d153fa71c5c282f774b5a53357d12340cd66a4d39dead7495e3260065548015e66f09136569c8ae38ec2c04d6a507499ff925bf623b2907da9a6100100"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 22:47:19.216077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:47:19.216451) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463546829.708786-1966450918 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 22:47:19.300933) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 22:47:19.301286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 22:47:19.301628) ========= ========= Total master_lag: 0.18 =========