builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1463784468.82 results: success (0) buildid: 20160520141432 builduid: 3128e432882a403988c6fb73adef99c7 revision: 44fa05b72b6fb2ef0edfe73e4767154ab99381bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.815544) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.816129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.816810) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.856632) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.856902) ========= 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=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 15:47:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.0M=0.001s 2016-05-20 15:47:49 (22.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.997479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:48.997789) ========= 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=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019889 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:49.048660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 15:47:49.048976) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44fa05b72b6fb2ef0edfe73e4767154ab99381bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 44fa05b72b6fb2ef0edfe73e4767154ab99381bc --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=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 15:47:49,688 truncating revision to first 12 chars 2016-05-20 15:47:49,689 Setting DEBUG logging. 2016-05-20 15:47:49,689 attempt 1/10 2016-05-20 15:47:49,689 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44fa05b72b6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 15:47:50,485 unpacking tar archive at: mozilla-central-44fa05b72b6f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.068991 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 15:47:50.130913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:50.131247) ========= script_repo_revision: 44fa05b72b6fb2ef0edfe73e4767154ab99381bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:50.131702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:50.131976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 15:47:50.142392) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 23 secs) (at 2016-05-20 15:47:50.142669) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:47:50 INFO - MultiFileLogger online at 20160520 15:47:50 in /builds/slave/test 15:47:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:47:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:50 INFO - {'append_to_log': False, 15:47:50 INFO - 'base_work_dir': '/builds/slave/test', 15:47:50 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:47:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:50 INFO - 'branch': 'Firefox-Non-PGO', 15:47:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:50 INFO - 'config_files': ('talos/linux_config.py',), 15:47:50 INFO - 'default_actions': ('clobber', 15:47:50 INFO - 'read-buildbot-config', 15:47:50 INFO - 'download-and-extract', 15:47:50 INFO - 'populate-webroot', 15:47:50 INFO - 'create-virtualenv', 15:47:50 INFO - 'install', 15:47:50 INFO - 'run-tests'), 15:47:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:50 INFO - 'download_minidump_stackwalk': True, 15:47:50 INFO - 'download_symbols': 'ondemand', 15:47:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:50 INFO - 'installer_path': 'installer.exe', 15:47:50 INFO - 'log_level': 'info', 15:47:50 INFO - 'log_name': 'talos', 15:47:50 INFO - 'log_to_console': True, 15:47:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:47:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:47:50 INFO - 'opt_config_files': (), 15:47:50 INFO - 'pip_index': False, 15:47:50 INFO - 'sps_profile': False, 15:47:50 INFO - 'sps_profile_interval': 0, 15:47:50 INFO - 'suite': 'dromaeojs', 15:47:50 INFO - 'system_bits': '32', 15:47:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:50 INFO - 'title': 'talos-linux64-ix-045', 15:47:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:50 INFO - 'use_talos_json': True, 15:47:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:50 INFO - 'work_dir': 'build'} 15:47:50 INFO - ##### 15:47:50 INFO - ##### Running clobber step. 15:47:50 INFO - ##### 15:47:50 INFO - Running pre-action listener: _resource_record_pre_action 15:47:50 INFO - Running main action method: clobber 15:47:50 INFO - rmtree: /builds/slave/test/build 15:47:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:51 INFO - Running post-action listener: _resource_record_post_action 15:47:51 INFO - ##### 15:47:51 INFO - ##### Finished clobber step (success) 15:47:51 INFO - ##### 15:47:51 INFO - ##### 15:47:51 INFO - ##### Running read-buildbot-config step. 15:47:51 INFO - ##### 15:47:51 INFO - Running pre-action listener: _resource_record_pre_action 15:47:51 INFO - Running main action method: read_buildbot_config 15:47:51 INFO - Using buildbot properties: 15:47:51 INFO - { 15:47:51 INFO - "project": "", 15:47:51 INFO - "product": "firefox", 15:47:51 INFO - "script_repo_revision": "production", 15:47:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:47:51 INFO - "repository": "", 15:47:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:47:51 INFO - "buildid": "20160520141432", 15:47:51 INFO - "pgo_build": "False", 15:47:51 INFO - "basedir": "/builds/slave/test", 15:47:51 INFO - "buildnumber": 591, 15:47:51 INFO - "slavename": "talos-linux64-ix-045", 15:47:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:47:51 INFO - "platform": "ubuntu64_hw", 15:47:51 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:47:51 INFO - "branch": "mozilla-central", 15:47:51 INFO - "repo_path": "mozilla-central", 15:47:51 INFO - "revision": "44fa05b72b6fb2ef0edfe73e4767154ab99381bc", 15:47:51 INFO - "stage_platform": "linux64", 15:47:51 INFO - "builduid": "3128e432882a403988c6fb73adef99c7", 15:47:51 INFO - "slavebuilddir": "test" 15:47:51 INFO - } 15:47:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:47:51 INFO - Running post-action listener: _resource_record_post_action 15:47:51 INFO - ##### 15:47:51 INFO - ##### Finished read-buildbot-config step (success) 15:47:51 INFO - ##### 15:47:51 INFO - ##### 15:47:51 INFO - ##### Running download-and-extract step. 15:47:51 INFO - ##### 15:47:51 INFO - Running pre-action listener: _resource_record_pre_action 15:47:51 INFO - Running main action method: download_and_extract 15:47:51 INFO - mkdir: /builds/slave/test/build/tests 15:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:51 INFO - https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:47:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:47:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:47:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:47:53 INFO - Downloaded 1361 bytes. 15:47:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:47:53 INFO - Using the following test package requirements: 15:47:53 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:47:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:47:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:47:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'jsshell-linux-x86_64.zip'], 15:47:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:47:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:47:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:47:53 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:47:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:47:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:47:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:53 INFO - https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:47:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:47:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:47:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:48:00 INFO - Downloaded 22814329 bytes. 15:48:00 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'] 15:48:00 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 15:48:01 INFO - Return code: 0 15:48:01 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 15:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:01 INFO - https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:48:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:48:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:51:32 INFO - Downloaded 22814329 bytes. 15:51:32 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'] 15:51:32 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 15:51:34 INFO - Return code: 0 15:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:34 INFO - https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:51:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:51:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:51:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:51:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:51:35 INFO - Downloaded 10840359 bytes. 15:51:35 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'] 15:51:35 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 15:51:35 INFO - Return code: 0 15:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:35 INFO - https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:51:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:51:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:51:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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 15:51:38 INFO - Downloaded 54820955 bytes. 15:51:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:51:38 INFO - mkdir: /builds/slave/test/properties 15:51:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:38 INFO - Contents: 15:51:38 INFO - build_url:https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:51:40 INFO - Running post-action listener: _resource_record_post_action 15:51:40 INFO - Running post-action listener: set_extra_try_arguments 15:51:40 INFO - ##### 15:51:40 INFO - ##### Finished download-and-extract step (success) 15:51:40 INFO - ##### 15:51:40 INFO - ##### 15:51:40 INFO - ##### Running populate-webroot step. 15:51:40 INFO - ##### 15:51:40 INFO - Running pre-action listener: _resource_record_pre_action 15:51:40 INFO - Running main action method: populate_webroot 15:51:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:40 INFO - '--tppagecycles', 15:51:40 INFO - '7'], 15:51:40 INFO - 'tests': ['tsvgm']}}, 15:51:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['damp', 'tps']}, 15:51:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'tests': ['damp', 'tps']}, 15:51:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['dromaeo_dom']}, 15:51:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['a11yr', 15:51:40 INFO - 'ts_paint', 15:51:40 INFO - 'tpaint', 15:51:40 INFO - 'sessionrestore', 15:51:40 INFO - 'sessionrestore_no_auto_restore', 15:51:40 INFO - 'tabpaint']}, 15:51:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['tsvgx', 15:51:40 INFO - 'tsvgr_opacity', 15:51:40 INFO - 'tart', 15:51:40 INFO - 'tscrollx', 15:51:40 INFO - 'cart']}, 15:51:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:40 INFO - 'tsvgr_opacity', 15:51:40 INFO - 'tart', 15:51:40 INFO - 'tscrollx', 15:51:40 INFO - 'cart']}, 15:51:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'talos_options': ['--disable-e10s'], 15:51:40 INFO - 'tests': ['tp5o']}, 15:51:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'tests': ['tp5o']}, 15:51:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'talos_options': ['--disable-e10s', 15:51:40 INFO - '--xperf_path', 15:51:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:40 INFO - 'tests': ['tp5n']}, 15:51:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:40 INFO - 'talos_options': ['--xperf_path', 15:51:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:40 INFO - 'tests': ['tp5n']}}, 15:51:40 INFO - 'talos.zip': {'path': '', 15:51:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:40 INFO - Running post-action listener: _resource_record_post_action 15:51:40 INFO - ##### 15:51:40 INFO - ##### Finished populate-webroot step (success) 15:51:40 INFO - ##### 15:51:40 INFO - ##### 15:51:40 INFO - ##### Running create-virtualenv step. 15:51:40 INFO - ##### 15:51:40 INFO - Running pre-action listener: _resource_record_pre_action 15:51:40 INFO - Running main action method: create_virtualenv 15:51:40 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:51:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:40 INFO - Using real prefix '/usr' 15:51:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:41 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:43 INFO - Installing pip.................done. 15:51:43 INFO - Return code: 0 15:51:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:43 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:51:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:43 INFO - Using env: {'DISPLAY': ':0', 15:51:43 INFO - 'HOME': '/home/cltbld', 15:51:43 INFO - 'LANG': 'en_US.UTF-8', 15:51:43 INFO - 'LANGUAGE': 'en_US:en', 15:51:43 INFO - 'LOGNAME': 'cltbld', 15:51:43 INFO - 'MAIL': '/var/mail/cltbld', 15:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:43 INFO - 'MOZ_NO_REMOTE': '1', 15:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:43 INFO - 'NO_EM_RESTART': '1', 15:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:43 INFO - 'PWD': '/builds/slave/test', 15:51:43 INFO - 'SHELL': '/bin/bash', 15:51:43 INFO - 'SHLVL': '1', 15:51:43 INFO - 'TERM': 'linux', 15:51:43 INFO - 'TMOUT': '86400', 15:51:43 INFO - 'USER': 'cltbld', 15:51:43 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:43 INFO - '_': '/tools/buildbot/bin/python'} 15:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:51:43 INFO - Cleaning up... 15:51:43 INFO - Return code: 0 15:51:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:43 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:51:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:51:43 INFO - Using env: {'DISPLAY': ':0', 15:51:43 INFO - 'HOME': '/home/cltbld', 15:51:43 INFO - 'LANG': 'en_US.UTF-8', 15:51:43 INFO - 'LANGUAGE': 'en_US:en', 15:51:43 INFO - 'LOGNAME': 'cltbld', 15:51:43 INFO - 'MAIL': '/var/mail/cltbld', 15:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:43 INFO - 'MOZ_NO_REMOTE': '1', 15:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:43 INFO - 'NO_EM_RESTART': '1', 15:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:43 INFO - 'PWD': '/builds/slave/test', 15:51:43 INFO - 'SHELL': '/bin/bash', 15:51:43 INFO - 'SHLVL': '1', 15:51:43 INFO - 'TERM': 'linux', 15:51:43 INFO - 'TMOUT': '86400', 15:51:43 INFO - 'USER': 'cltbld', 15:51:43 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:43 INFO - '_': '/tools/buildbot/bin/python'} 15:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:43 INFO - Downloading/unpacking psutil>=3.1.1 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:45 INFO - Installing collected packages: psutil 15:51:45 INFO - Running setup.py install for psutil 15:51:45 INFO - building 'psutil._psutil_linux' extension 15:51:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:51:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:51:45 INFO - building 'psutil._psutil_posix' extension 15:51:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:51:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:51:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:46 INFO - Successfully installed psutil 15:51:46 INFO - Cleaning up... 15:51:46 INFO - Return code: 0 15:51:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:51:46 INFO - Using env: {'DISPLAY': ':0', 15:51:46 INFO - 'HOME': '/home/cltbld', 15:51:46 INFO - 'LANG': 'en_US.UTF-8', 15:51:46 INFO - 'LANGUAGE': 'en_US:en', 15:51:46 INFO - 'LOGNAME': 'cltbld', 15:51:46 INFO - 'MAIL': '/var/mail/cltbld', 15:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:46 INFO - 'MOZ_NO_REMOTE': '1', 15:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:46 INFO - 'NO_EM_RESTART': '1', 15:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:46 INFO - 'PWD': '/builds/slave/test', 15:51:46 INFO - 'SHELL': '/bin/bash', 15:51:46 INFO - 'SHLVL': '1', 15:51:46 INFO - 'TERM': 'linux', 15:51:46 INFO - 'TMOUT': '86400', 15:51:46 INFO - 'USER': 'cltbld', 15:51:46 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:46 INFO - '_': '/tools/buildbot/bin/python'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:51:48 INFO - Installing collected packages: mozsystemmonitor 15:51:48 INFO - Running setup.py install for mozsystemmonitor 15:51:48 INFO - Successfully installed mozsystemmonitor 15:51:48 INFO - Cleaning up... 15:51:48 INFO - Return code: 0 15:51:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:48 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:51:48 INFO - Using env: {'DISPLAY': ':0', 15:51:48 INFO - 'HOME': '/home/cltbld', 15:51:48 INFO - 'LANG': 'en_US.UTF-8', 15:51:48 INFO - 'LANGUAGE': 'en_US:en', 15:51:48 INFO - 'LOGNAME': 'cltbld', 15:51:48 INFO - 'MAIL': '/var/mail/cltbld', 15:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:48 INFO - 'MOZ_NO_REMOTE': '1', 15:51:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:48 INFO - 'NO_EM_RESTART': '1', 15:51:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:48 INFO - 'PWD': '/builds/slave/test', 15:51:48 INFO - 'SHELL': '/bin/bash', 15:51:48 INFO - 'SHLVL': '1', 15:51:48 INFO - 'TERM': 'linux', 15:51:48 INFO - 'TMOUT': '86400', 15:51:48 INFO - 'USER': 'cltbld', 15:51:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:48 INFO - '_': '/tools/buildbot/bin/python'} 15:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:48 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:51 INFO - Downloading docopt-0.6.1.tar.gz 15:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:51 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:51 INFO - Running setup.py install for blobuploader 15:51:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:51 INFO - Running setup.py install for requests 15:51:51 INFO - Running setup.py install for docopt 15:51:51 INFO - Successfully installed blobuploader requests docopt 15:51:51 INFO - Cleaning up... 15:51:51 INFO - Return code: 0 15:51:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:51 INFO - Using env: {'DISPLAY': ':0', 15:51:51 INFO - 'HOME': '/home/cltbld', 15:51:51 INFO - 'LANG': 'en_US.UTF-8', 15:51:51 INFO - 'LANGUAGE': 'en_US:en', 15:51:51 INFO - 'LOGNAME': 'cltbld', 15:51:51 INFO - 'MAIL': '/var/mail/cltbld', 15:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:51 INFO - 'MOZ_NO_REMOTE': '1', 15:51:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:51 INFO - 'NO_EM_RESTART': '1', 15:51:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:51 INFO - 'PWD': '/builds/slave/test', 15:51:51 INFO - 'SHELL': '/bin/bash', 15:51:51 INFO - 'SHLVL': '1', 15:51:51 INFO - 'TERM': 'linux', 15:51:51 INFO - 'TMOUT': '86400', 15:51:51 INFO - 'USER': 'cltbld', 15:51:51 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:51 INFO - '_': '/tools/buildbot/bin/python'} 15:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:52 INFO - Running setup.py (path:/tmp/pip-_OYy2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:52 INFO - Running setup.py (path:/tmp/pip-sqYki0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:52 INFO - Running setup.py (path:/tmp/pip-Es1I8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:52 INFO - Running setup.py (path:/tmp/pip-bDgf3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:52 INFO - Running setup.py (path:/tmp/pip-q8yacM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:52 INFO - Running setup.py (path:/tmp/pip-dMMGuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:52 INFO - Running setup.py (path:/tmp/pip-zXz6rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:52 INFO - Running setup.py (path:/tmp/pip-z3u3aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:52 INFO - Running setup.py (path:/tmp/pip-O1nmS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:52 INFO - Running setup.py (path:/tmp/pip-4Kjyj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:52 INFO - Running setup.py (path:/tmp/pip-RFILp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:52 INFO - Running setup.py (path:/tmp/pip-pTh7OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:52 INFO - Running setup.py (path:/tmp/pip-B3KLxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:53 INFO - Running setup.py (path:/tmp/pip-as9A8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:53 INFO - Running setup.py (path:/tmp/pip-Y8fYPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:53 INFO - Running setup.py (path:/tmp/pip-0kKW6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:53 INFO - Running setup.py (path:/tmp/pip-AcqLyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:53 INFO - Running setup.py install for manifestparser 15:51:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:53 INFO - Running setup.py install for mozcrash 15:51:53 INFO - Running setup.py install for mozdebug 15:51:53 INFO - Running setup.py install for mozdevice 15:51:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:53 INFO - Running setup.py install for mozfile 15:51:53 INFO - Running setup.py install for mozhttpd 15:51:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:53 INFO - Running setup.py install for mozinfo 15:51:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for mozInstall 15:51:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for mozleak 15:51:54 INFO - Running setup.py install for mozlog 15:51:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for moznetwork 15:51:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for mozprocess 15:51:54 INFO - Running setup.py install for mozprofile 15:51:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for mozrunner 15:51:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:54 INFO - Running setup.py install for mozscreenshot 15:51:54 INFO - Running setup.py install for moztest 15:51:55 INFO - Running setup.py install for mozversion 15:51:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:55 INFO - Cleaning up... 15:51:55 INFO - Return code: 0 15:51:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:55 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:55 INFO - Using env: {'DISPLAY': ':0', 15:51:55 INFO - 'HOME': '/home/cltbld', 15:51:55 INFO - 'LANG': 'en_US.UTF-8', 15:51:55 INFO - 'LANGUAGE': 'en_US:en', 15:51:55 INFO - 'LOGNAME': 'cltbld', 15:51:55 INFO - 'MAIL': '/var/mail/cltbld', 15:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:55 INFO - 'MOZ_NO_REMOTE': '1', 15:51:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:55 INFO - 'NO_EM_RESTART': '1', 15:51:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:55 INFO - 'PWD': '/builds/slave/test', 15:51:55 INFO - 'SHELL': '/bin/bash', 15:51:55 INFO - 'SHLVL': '1', 15:51:55 INFO - 'TERM': 'linux', 15:51:55 INFO - 'TMOUT': '86400', 15:51:55 INFO - 'USER': 'cltbld', 15:51:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:55 INFO - '_': '/tools/buildbot/bin/python'} 15:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:55 INFO - Running setup.py (path:/tmp/pip-8jhMEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:55 INFO - Running setup.py (path:/tmp/pip-PoM44S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:55 INFO - Running setup.py (path:/tmp/pip-v9PXLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:55 INFO - Running setup.py (path:/tmp/pip-LQHMj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:55 INFO - Running setup.py (path:/tmp/pip-nJatT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:55 INFO - Running setup.py (path:/tmp/pip-F5sxaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:55 INFO - Running setup.py (path:/tmp/pip-amCCRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:55 INFO - Running setup.py (path:/tmp/pip-xuiixp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:55 INFO - Running setup.py (path:/tmp/pip-Z4XXzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:56 INFO - Running setup.py (path:/tmp/pip-MY2tyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:56 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)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:56 INFO - Running setup.py (path:/tmp/pip-jwUp7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:56 INFO - Running setup.py (path:/tmp/pip-UOTRJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:56 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)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:56 INFO - Running setup.py (path:/tmp/pip-lUvAUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:56 INFO - Running setup.py (path:/tmp/pip-H5QxB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:56 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)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:56 INFO - Running setup.py (path:/tmp/pip-8PeMJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:56 INFO - Running setup.py (path:/tmp/pip-m4XN5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:56 INFO - Running setup.py (path:/tmp/pip-0P51oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:58 INFO - Downloading blessings-1.6.tar.gz 15:51:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:58 INFO - Installing collected packages: blessings 15:51:58 INFO - Running setup.py install for blessings 15:51:58 INFO - Successfully installed blessings 15:51:58 INFO - Cleaning up... 15:51:58 INFO - Return code: 0 15:51:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:58 INFO - Reading from file tmpfile_stdout 15:51:58 INFO - Current package versions: 15:51:58 INFO - argparse == 1.2.1 15:51:58 INFO - blessings == 1.6 15:51:58 INFO - blobuploader == 1.2.4 15:51:58 INFO - docopt == 0.6.1 15:51:58 INFO - manifestparser == 1.1 15:51:58 INFO - mozInstall == 1.12 15:51:58 INFO - mozcrash == 0.17 15:51:58 INFO - mozdebug == 0.1 15:51:58 INFO - mozdevice == 0.48 15:51:58 INFO - mozfile == 1.2 15:51:58 INFO - mozhttpd == 0.7 15:51:58 INFO - mozinfo == 0.9 15:51:58 INFO - mozleak == 0.1 15:51:58 INFO - mozlog == 3.2 15:51:58 INFO - moznetwork == 0.27 15:51:58 INFO - mozprocess == 0.22 15:51:58 INFO - mozprofile == 0.28 15:51:58 INFO - mozrunner == 6.11 15:51:58 INFO - mozscreenshot == 0.1 15:51:58 INFO - mozsystemmonitor == 0.3 15:51:58 INFO - moztest == 0.7 15:51:58 INFO - mozversion == 1.4 15:51:58 INFO - psutil == 3.1.1 15:51:58 INFO - requests == 1.2.3 15:51:58 INFO - wsgiref == 0.1.2 15:51:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:58 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:58 INFO - Using env: {'DISPLAY': ':0', 15:51:58 INFO - 'HOME': '/home/cltbld', 15:51:58 INFO - 'LANG': 'en_US.UTF-8', 15:51:58 INFO - 'LANGUAGE': 'en_US:en', 15:51:58 INFO - 'LOGNAME': 'cltbld', 15:51:58 INFO - 'MAIL': '/var/mail/cltbld', 15:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:58 INFO - 'MOZ_NO_REMOTE': '1', 15:51:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:58 INFO - 'NO_EM_RESTART': '1', 15:51:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:58 INFO - 'PWD': '/builds/slave/test', 15:51:58 INFO - 'SHELL': '/bin/bash', 15:51:58 INFO - 'SHLVL': '1', 15:51:58 INFO - 'TERM': 'linux', 15:51:58 INFO - 'TMOUT': '86400', 15:51:58 INFO - 'USER': 'cltbld', 15:51:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:58 INFO - '_': '/tools/buildbot/bin/python'} 15:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:59 INFO - Cleaning up... 15:51:59 INFO - Return code: 0 15:51:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:59 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 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:59 INFO - Using env: {'DISPLAY': ':0', 15:51:59 INFO - 'HOME': '/home/cltbld', 15:51:59 INFO - 'LANG': 'en_US.UTF-8', 15:51:59 INFO - 'LANGUAGE': 'en_US:en', 15:51:59 INFO - 'LOGNAME': 'cltbld', 15:51:59 INFO - 'MAIL': '/var/mail/cltbld', 15:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:59 INFO - 'MOZ_NO_REMOTE': '1', 15:51:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:59 INFO - 'NO_EM_RESTART': '1', 15:51:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:59 INFO - 'PWD': '/builds/slave/test', 15:51:59 INFO - 'SHELL': '/bin/bash', 15:51:59 INFO - 'SHLVL': '1', 15:51:59 INFO - 'TERM': 'linux', 15:51:59 INFO - 'TMOUT': '86400', 15:51:59 INFO - 'USER': 'cltbld', 15:51:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:59 INFO - '_': '/tools/buildbot/bin/python'} 15:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:59 INFO - Downloading/unpacking jsonschema 15:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:52:01 INFO - Downloading/unpacking functools32 (from jsonschema) 15:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:01 INFO - Downloading functools32-3.2.3-2.tar.gz 15:52:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:52:01 INFO - warning: no files found matching '*.txt' 15:52:01 INFO - no previously-included directories found matching 'build' 15:52:01 INFO - no previously-included directories found matching 'dist' 15:52:01 INFO - no previously-included directories found matching '.git*' 15:52:01 INFO - Installing collected packages: jsonschema, functools32 15:52:01 INFO - Running setup.py install for functools32 15:52:01 INFO - warning: no files found matching '*.txt' 15:52:01 INFO - no previously-included directories found matching 'build' 15:52:01 INFO - no previously-included directories found matching 'dist' 15:52:01 INFO - no previously-included directories found matching '.git*' 15:52:01 INFO - Successfully installed jsonschema functools32 15:52:01 INFO - Cleaning up... 15:52:01 INFO - Return code: 0 15:52:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39f682f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b8f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, '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': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:52:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:52:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:52:01 INFO - Using env: {'DISPLAY': ':0', 15:52:01 INFO - 'HOME': '/home/cltbld', 15:52:01 INFO - 'LANG': 'en_US.UTF-8', 15:52:01 INFO - 'LANGUAGE': 'en_US:en', 15:52:01 INFO - 'LOGNAME': 'cltbld', 15:52:01 INFO - 'MAIL': '/var/mail/cltbld', 15:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:01 INFO - 'MOZ_NO_REMOTE': '1', 15:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:01 INFO - 'NO_EM_RESTART': '1', 15:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:01 INFO - 'PWD': '/builds/slave/test', 15:52:01 INFO - 'SHELL': '/bin/bash', 15:52:01 INFO - 'SHLVL': '1', 15:52:01 INFO - 'TERM': 'linux', 15:52:01 INFO - 'TMOUT': '86400', 15:52:01 INFO - 'USER': 'cltbld', 15:52:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:01 INFO - '_': '/tools/buildbot/bin/python'} 15:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:01 INFO - Downloading/unpacking flake8 15:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:52:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:52:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:52:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:52:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:52:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:52:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:52:04 INFO - no previously-included directories found matching 'docs/_build' 15:52:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:52:04 INFO - Running setup.py install for pep8 15:52:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:52:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:52:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:52:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:52:04 INFO - no previously-included directories found matching 'docs/_build' 15:52:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:52:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:52:04 INFO - Cleaning up... 15:52:04 INFO - Return code: 0 15:52:04 INFO - Running post-action listener: _resource_record_post_action 15:52:04 INFO - Running post-action listener: _start_resource_monitoring 15:52:04 INFO - Starting resource monitoring. 15:52:04 INFO - ##### 15:52:04 INFO - ##### Finished create-virtualenv step (success) 15:52:04 INFO - ##### 15:52:04 INFO - ##### 15:52:04 INFO - ##### Running install step. 15:52:04 INFO - ##### 15:52:04 INFO - Running pre-action listener: _resource_record_pre_action 15:52:04 INFO - Running main action method: install 15:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:52:04 INFO - Reading from file tmpfile_stdout 15:52:04 INFO - Detecting whether we're running mozinstall >=1.0... 15:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:52:05 INFO - Reading from file tmpfile_stdout 15:52:05 INFO - Output received: 15:52:05 INFO - Usage: mozinstall [options] installer 15:52:05 INFO - Options: 15:52:05 INFO - -h, --help show this help message and exit 15:52:05 INFO - -d DEST, --destination=DEST 15:52:05 INFO - Directory to install application into. [default: 15:52:05 INFO - "/builds/slave/test"] 15:52:05 INFO - --app=APP Application being installed. [default: firefox] 15:52:05 INFO - mkdir: /builds/slave/test/build/application 15:52:05 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'] 15:52:05 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 15:52:20 INFO - Reading from file tmpfile_stdout 15:52:20 INFO - Output received: 15:52:20 INFO - /builds/slave/test/build/application/firefox/firefox 15:52:20 INFO - Running post-action listener: _resource_record_post_action 15:52:20 INFO - ##### 15:52:20 INFO - ##### Finished install step (success) 15:52:20 INFO - ##### 15:52:20 INFO - ##### 15:52:20 INFO - ##### Running run-tests step. 15:52:20 INFO - ##### 15:52:20 INFO - Running pre-action listener: _resource_record_pre_action 15:52:20 INFO - Running main action method: run_tests 15:52:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:20 INFO - Python 2.7.3 15:52:20 INFO - Return code: 0 15:52:20 INFO - grabbing minidump binary from tooltool 15:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:20 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 0x1ce99c0>, '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 0x1cea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cead50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:52:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:52:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:52:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:20 INFO - Return code: 0 15:52:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:52:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:52:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:52:21 INFO - Return code: 0 15:52:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:52:21 INFO - Using env: {'DISPLAY': ':0', 15:52:21 INFO - 'HOME': '/home/cltbld', 15:52:21 INFO - 'LANG': 'en_US.UTF-8', 15:52:21 INFO - 'LANGUAGE': 'en_US:en', 15:52:21 INFO - 'LOGNAME': 'cltbld', 15:52:21 INFO - 'MAIL': '/var/mail/cltbld', 15:52:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:21 INFO - 'MOZ_NO_REMOTE': '1', 15:52:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:21 INFO - 'NO_EM_RESTART': '1', 15:52:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:21 INFO - 'PWD': '/builds/slave/test', 15:52:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:21 INFO - 'SHELL': '/bin/bash', 15:52:21 INFO - 'SHLVL': '1', 15:52:21 INFO - 'TERM': 'linux', 15:52:21 INFO - 'TMOUT': '86400', 15:52:21 INFO - 'USER': 'cltbld', 15:52:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463773499.121239-699048575', 15:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:21 INFO - '_': '/tools/buildbot/bin/python'} 15:52:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:52:22 INFO - mozversion application_buildid: 20160520141432 15:52:22 INFO - mozversion application_changeset: 44fa05b72b6fb2ef0edfe73e4767154ab99381bc 15:52:22 INFO - mozversion application_display_name: Nightly 15:52:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:22 INFO - mozversion application_name: Firefox 15:52:22 INFO - mozversion application_remotingname: firefox 15:52:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:52:22 INFO - mozversion application_vendor: Mozilla 15:52:22 INFO - mozversion application_version: 49.0a1 15:52:22 INFO - mozversion platform_buildid: 20160520141432 15:52:22 INFO - mozversion platform_changeset: 44fa05b72b6fb2ef0edfe73e4767154ab99381bc 15:52:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:52:22 INFO - mozversion platform_version: 49.0a1 15:52:22 INFO - using testdate: 1463784742 15:52:22 INFO - actual date: 1463784742 15:52:22 INFO - starting webserver on 'localhost:41145' 15:52:22 INFO - SUITE-START | Running 2 tests 15:52:22 INFO - TEST-START | dromaeo_css 15:52:22 INFO - operating with platform_type : linux_ 15:52:22 INFO - Initialising browser for dromaeo_css test... 15:52:22 INFO - TEST-INFO | started process 6703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtlPbj/profile http://localhost:41145/getInfo.html) 15:52:26 INFO - PROCESS | 6703 | __metrics Screen width/height:1600/1200 15:52:26 INFO - PROCESS | 6703 | colorDepth:24 15:52:26 INFO - PROCESS | 6703 | Browser inner width/height: 1024/697 15:52:26 INFO - PROCESS | 6703 | __metrics 15:52:27 ERROR - PROCESS | 6703 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:52:28 INFO - TEST-INFO | 6703: exit 0 15:52:28 INFO - Browser initialized. 15:52:28 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:28 INFO - TEST-INFO | started process 6783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtlPbj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:40 INFO - PROCESS | 6783 | RSS: Main: 154845184 15:52:40 INFO - PROCESS | 6783 | 15:53:31 INFO - PROCESS | 6783 | 1463784811167 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:55:43 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41145/tests/dromaeo/cssquery-ext.html) 15:55:44 INFO - PROCESS | 6783 | RSS: Main: 266788864 15:55:44 INFO - PROCESS | 6783 | 15:58:47 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-ext.html (next: http://localhost:41145/tests/dromaeo/cssquery-jquery.html) 15:58:48 INFO - PROCESS | 6783 | RSS: Main: 234168320 15:58:48 INFO - PROCESS | 6783 | 16:01:50 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41145/tests/dromaeo/cssquery-mootools.html) 16:01:50 INFO - PROCESS | 6783 | RSS: Main: 234831872 16:01:50 INFO - PROCESS | 6783 | 16:04:52 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41145/tests/dromaeo/cssquery-prototype.html) 16:04:53 INFO - PROCESS | 6783 | RSS: Main: 228540416 16:04:53 INFO - PROCESS | 6783 | 16:07:56 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41145/tests/dromaeo/cssquery-yui.html) 16:07:57 INFO - PROCESS | 6783 | RSS: Main: 218013696 16:07:57 INFO - PROCESS | 6783 | 16:10:59 INFO - PROCESS | 6783 | Cycle 1(1): loaded http://localhost:41145/tests/dromaeo/cssquery-yui.html (next: http://localhost:41145/tests/dromaeo/cssquery-dojo.html) 16:11:00 INFO - PROCESS | 6783 | RSS: Main: 272596992 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | __start_tp_report 16:11:00 INFO - PROCESS | 6783 | _x_x_mozilla_page_load 16:11:00 INFO - PROCESS | 6783 | _x_x_mozilla_page_load_details 16:11:00 INFO - PROCESS | 6783 | |i|pagename|runs| 16:11:00 INFO - PROCESS | 6783 | |0;dojo.html;2710.3901622135018;2722.6868910075345;2739.3973325868315;2754.9724502754966;2776.7778577713784;4616.3767891334655;4692.131955588218;4735.597474214692;4840.370751802269;4867.367242258506;4688.953110468894;4704.811807527703;4710.74090924999;4757.262624293231;4778.474367819537;4300.913981720364;4346.239408103585;4388.056567837914;4421.425214722081;4551.8634440966825;4471.172833025885;4494.752788596626;4499.1001799640035;4514.729116253019;4558.270676691714;4271.295236286009;4316.115196384733;4322.286822674255;4356.281213599752;4428.202923473774;4349.304111342201;4366.104948485553;4370.584794444522;4411.580899814512;4412.558744125594;4394.472663280395;4410.933835992462;4416.492103408117;4416.536054068613;4438.644908407319;4524.140413321458;4628;4672.229082201433;4677.134730074947;4766.499517550642;5131.281620573122;5185.299984502081;5198.506141916512;5201.193814958688;5203.7398130093725;5183.092958732207;5223;5235.214717792323;5248.160294352885;5249.685018898878;4633.559811817872;4739.360186374795;4783.04339132168;4793.736344501086;4832.516748325174;5336.306280183629;5342.679439233659;5350.5184533392185;5351.384590772033;5355.86610334737;5112.130937740593;5117.2068329409;5124.666896651705;5126.6923984561045;5135.332406787168;4904.55858972687;5065.468125846806;5135.974320128375;5136.255242989746;5150.510701483353;5084.237364395371;5111.539961403417;5112.207607820719;5116.599036898887;5169.767360468788;4885.60915126789;5088.796448142107;5097.541221290102;5100.005498927748;5101.464346243591;4287.656987440997;4307.547707490731;4333.349997500401;4337.132573485253;4351.26028575143;3795.221979494197;3799.35410980134;3833.5783063862955;3836.156045669948;3877.9612203878164;4337.783110844445;4345.435093437833;4348.304271316638;4392.824287028547;4395.099004704028;4892.804287828518;4931.728754918514;5089;5112.74436278192;5128.769205385766;4399.956000440018;4405.052913623586;4412.404325416028;4435.822567097344;4453.354263631755;4652.046330502239;4679.766011699401;4692.319613656001;4695.577398034194;4742.715437073786;4641.535846415467;4723.811047558197;4761.095391875532;4785.736784476887;4844.75776211181;5151.175811870083;5157.561607263425;5160.48395160481;5169.4830516948;5212.9739351304515;4800.3759511263315;4801.927971080367;4806.447258565309;4811.398575178103;4860.781264843161;4888.120138375127;4958.950410495849;4972.154733695208;5015.623828212826;5020.698758074527;5066.4680208577565;5090.389153301628;5095.363079615048;5097.898042039213;5139.100657384868;4780.235162374003;4932.728699921395;4939.9259011114145;4942.980927244573;4948.257761335828;4916.582090522203;4950.356453661001;4986.700797952134;4990.426100998432;5004.899902002013;4907.656464047483;4915.483874193158;4959.107360675113;4960.528749768766;4962.751862406937;4818.638602104786;4919.901601968012;5037.899242015213;5083.491650834887;5093.439721630543;4910.975445122752;4937.35814344133;4939.234418665113;4943.728094954668;4988.975055124701 16:11:00 INFO - PROCESS | 6783 | |1;ext.html;553.1683706228081;587.9970600146999;596.73445316834;597.2295738497338;600.1208229943138;26837.73162268377;29192.85403572982;39470.40794388079;40351.39472907909;40489.59510404895;40576.188476230454;41027.38458923111;41112.58887411125;41142.794286028606;41224.381634275436;7797.610119494031;7952;7968.203179682044;7982.640781164832;7986.560739159344;7781.961090194541;7906.327962123227;7918.366530677532;7918.960405197995;7924.207579242087;7906.920930790676;7934.642941067637;7947;7947.761567152966;7951.801204969865;7892.486988345768;7903.288704016609;7918.4457088003865;7923.12845586985;7934.047914250327;7852.764417067498;7961.761147165595;7970.402219833536;7978;7993.880091798627;38641.22717545662;39178;39183;39211.607883921366;39221.60778392208;3011.2170835582688;3155;3253.96746032539;3576.302620988908;3624.02151419118;27065.32336691579;29465.41069178626;29615.111546653636;29840;30662.38675226483;3459.6367381425093;3500.352434799545;3500.842462089212;3623.855045798165;3668.523091998023;254423;255023;255511.72244138847;255578;256500;54303;55214.447855521736;55309;55433.168502472494;55510.444895551336;34687.30625387478;35431.291374172375;35480.29039419197;35490.11274718153;35539;12866.678333041562;13050.54323098687;13075.476980920846;13107.475700972045;13107.803382949263;9798.65704700332;9921.950390248003;9926.15627671656;9933.3543319684;9935.354201976992;18547.536311592317;18591.90704046471;18794.812051879308;18963.715544266848;19021.90489047546;6904.758333458305;6915.4121899638085;6968.895466568006;7032.191298000693;7161.140663120459;809.6356639512126;879.5382424227204;965.3290962780941;975.9072888075622;1012.6607586458472;27122.321941951213;27973.72026279752;28415.43169136606;28634.713652863204;28764.137075887338;954.1269738189618;954.1412728544227;965.7006328038331;976.6581696406201;977.8777652793401;14823.703525929637;15082.622934426727;15092.396304147931;15277.38890444392;15444;392034;396159;397374;397783;399566;48918.51081489139;49296.75351623291;49401;49430;49448;1094.414488248783;1164.2839653612966;1189.9940500297616;1206.6862615719976;1209.8185272209064;28275.293117672223;28493;29035.419291613627;29278.26804329909;29292;41229.1754164909;41242.58757412388;41752;41849.79075104605;41854.581454186286;133688;133689.33155334162;133998;134290;134377;22196.223132190295;24753.87623061873;25163.24510264695;25201.49597008086;25413.61879571845;40942.38586421266;41314.1737165261;41488.1702365957;41634.583654164286;42063.579364205965;35839.28321433609;36075.458868116475;36294.8185259072;36567.817160914026;36799.816000919825;35158.47262291119;35366.29267414689;35702.28595428129;35770.64229357673;35943;34846.825765872025;35679.64320356763;35934.64065359313;36023.09942251465;36118.27763444664;30631.846840765655;31806.204844879063;32455;32547.18632034218;32569.674303256663;24387.658678772837;25202.87398563069;25263.36841578975;25300.620490692287;25443.36391590225 16:11:00 INFO - PROCESS | 6783 | |2;jquery.html;362.2121884900342;367.0969415238513;369.57683452446946;374.5075872647501;375.1109869609024;15812.130332831704;17041.403550875722;17160.39938602149;17462;17599.20803563841;16155.111468869207;16575.751363729552;17168.22742976566;17275.568110797238;17297.30810767568;5007.2739452779415;5203.349581302337;5203.817866374678;5205.583553315726;5215.243789650498;3722.702183825301;3884.7086468514835;5092.974535127338;5682.971585142089;6018.7893423730175;6058.454739073482;6061.757529698807;6098.237720284965;6098.817035488943;6106.786262480814;5875.236219291507;5914.792982245623;5957.523398128161;5969.403059694017;6030.487408570277;3386.6444023377685;4901.460839307673;5216.217567364888;5216.739163041871;5274.077036518594;19808.603827923504;20512;20583.691244631344;20613.896930515402;20623.89688051565;1980.23760852073;2083.593699228651;2164.848460607758;2513.899444022237;2523.608840629687;12741.745165096647;12805.295708736016;12966.87033129666;13188.274644894527;13605.727885442237;1754.5175076853968;1755.7629719987765;1777.369033992927;1809.1225755508567;1820.3719716697717;66696.6665166676;66812;66823.66588167028;67081.66459167721;67160;9355.859662105075;9461.574229159703;9570.425774453555;9742.902570974342;10164.237682173867;13343.199408035549;13348.933255333855;13432.798508022388;13448.865511344762;13467.393967271497;6638.402543770987;6740.123783908159;6865.313468653095;6898.275681053417;6900.861982760317;6376.171097757262;6551.868962620721;6553;6701.698423570951;6727.93272067273;6052.66710330936;6161.815145545641;6248.875022499525;6278.246610406781;6348.111264422984;1665.1507731057247;1704.514213449161;1743.2678275124479;1755.131210051033;1781.7950935642307;836.786619412046;881.9470831750116;949.9762505937407;954.3891909178132;960.4765402855321;16469.094199818886;16955.40656077056;17152;17214;17605.911970440324;902.9413088149249;924.0910946695382;929.2798081486902;930.2232635749225;938.8779458670466;9604.135627793392;9873.802523949624;9923.553440095213;9949.651762188289;10014.699559013095;393796;403269;403965;406038;412677;67962.3203767956;68882;69330;69730.65134674395;70201.64899175472;940.4028441939417;949.9002604726543;950.7813202963357;951.5670369981503;972.7470857577081;15056.623584410478;15182.392704291704;15185.772213417029;15392.692146157238;15706.842931570538;33377.66622333843;33816.66183338135;34027.14932126716;34244;34489.13777155491;45760;46644.53355466402;47325.29012064753;47413.28880066733;47541.28688069613;7446.962765186356;7503.699852006077;7701.383889288955;7871.960640196991;7997.560134192442;9989.400635961865;10777.461126943464;11331;11751.294922304689;11899.226550274205;9090.000099989129;9788.461634610165;10120.089191972758;10179.236557258384;10492.265541412027;9889.26937785899;10007.449590272314;10077.596896124072;10083.445410502492;10114.241431892491;9694.563744631367;10130.79738405213;10150.187984961332;10339.483025848527;10601.363918164934;7794.376449884109;7807.18024607408;7840;7919.524828510308;8228.83542329162;10095.545700443645;10166.135878450414;10488.160947124365;10652.8934710655;10698.679039628823 16:11:00 INFO - PROCESS | 6783 | |3;mootools.html;1269.2130878855112;1392.3525560614316;1431.0698046269927;1515.6286709756112;1725.7842769653794;6753.696083676234;7399.149097853741;7713.305802477776;7719.691212351513;7738.303552680258;7325.3040961108745;7608.657610407531;7613.657385417656;7790.337821285183;7791.454598178129;2199.604071267171;2218.622834118196;2247.0562363807157;2253.076238742116;2253.2001139595454;2176.706144670465;2180.8800515971616;2238.418011317061;2239.4513344230686;2239.9328020159423;2213.7232845894264;2218.190360518407;2219.7003404540424;2229.320057382498;2251.0770584060538;2134.7758485359045;2141.7108690326763;2168.4578855286177;2169.4359466538654;2169.837262205333;2172.1094351315883;2192.802647761693;2194.8244140468723;2198.0328655391577;2198.120751699317;7386;7699.730509432143;7718.922810771876;7761.45669803114;7773.416993725436;4386.6929314947965;4474.7986340614425;4475.283954567253;4487.506374298824;4493.056458143794;2788.261110805638;2819.4502072095947;2845.942922758637;2876.942461150786;2882.7117288271215;892.1791951404723;918.8575770755543;919.5310391700281;928.6796055360958;929.8884133903907;10394.428306443142;10551.15590752738;10659.733506662318;10660.200484963581;10662.680119596425;4134.441850350225;4207.915841683149;4276.914461710749;4292.978535107304;4306.461692288464;8979.14698103679;9022.368434209673;9049.954750226338;9079.319051071196;9087.13672201153;4404.097160082176;4457.041736026705;4464.99537604043;4472.127935052634;4480.103979204172;3520.6655367740022;3912.4326972588815;4029.979850100731;4039.899002524902;4102.015516276072;3643.9817800911355;3691.1694868654877;3694.464302676097;3748.737588368788;3792.222594368203;1176.8940795328463;1209.782239196953;1210.0501106631427;1221.4015132584968;1227.9017678585692;716.7168968257613;813.4509206285852;838.3502785341409;840.2983508770245;862.7929296968808;5189.351331083614;5204.4535323791215;5398.568114550826;5522.2544956431175;5601.411851755588;817;885.2386947225381;898.0256421782309;906.4787747045475;921.2767977137914;3796.8101594920145;3822.636849499349;3847.5960024197057;3960.2673505401017;3991.1419044905483;9725.854112188323;9911.306208565331;9917.752056198653;9939;9953.80092398148;9717;9755.756106097404;9909.306348555532;9933;9939.360005598995;7283.089613798275;7437.405007599487;7452.776416707507;7470.477066605394;7473.327400533978;5228.843134705813;5513.448655134454;5555.472230138131;5615.578831587565;5626.549876009991;8796.648134074565;8913.598888049924;8988.595513202052;9107.08929107084;9129.315301352557;8530;9051.54742262871;9276.814463710823;9289.396189247676;9307.488088155216;6146.477549408171;6386.648734319657;6570.441512471493;6620.404163625297;6768.492363072691;7479.401647868266;7517.586532740533;7572.545647261182;7588;7594.924050759642;6572.277049524453;6682.899756503559;6694.196696396463;6757.526973111835;6983.336583024605;6616.867662646624;6671.6997735103005;6728.428083612947;6813.965930170318;6833.214180369122;6173.043178307369;6275.215598050244;6360.268569114611;6367.90448143269;6369.745210191726;4616.146012987608;4647.674662773574;4691.5308469152815;4712.622990160848;4730.574248317668;4537.636989040803;4635.351050852951;4658;4669.042846216449;4727.550882666142 16:11:00 INFO - PROCESS | 6783 | |4;prototype.html;281.1761538691634;286.80927183423023;295.42539760166477;301.6425535740149;304.47174152844815;4821.445533763618;4831.444383895853;4838.733869637169;4849.224124140138;4854.441739199993;4536.795844187011;4602.9873427845905;4649.349091127245;4784.330193772875;4800.615950723943;3819.236152769457;3850.942235866464;3864.111254411487;3865.381538953768;3868.3810590305557;3829.9617003829885;3835.1179228777396;3851.114243723945;3860.4402361657544;3872.244912242114;3589.210373717778;3676.375016247231;3791.393377059671;3799.6960243180615;3800.5629352624387;3699.463577781207;3743.288775132716;3776.339140650375;3778.2985165606588;3794.867179648713;3364.5794275715534;3384.9492257616157;3385.221399078201;3400.4527939787336;3412.6416726243883;4376.715513491613;4665.393498845162;4680.4149481314835;4685.789139488731;4704.529547045268;4263;4284.421603083575;4297.78511074445;4310.4266942566055;4317.417148684919;4972.801087956477;5041.395032596112;5050.974745126251;5051.1413059779925;5068.935523540061;3601.2977469393477;3603.1712706077487;3603.40543810271;3651.4157734762575;3667.94498082529;5033.974830125826;5290.576753859682;5293.179557168645;5293.708846013467;5357.8392648220615;3638.872639457606;3681.9079523012138;3687.59436461989;3703.6481534254203;3709.86682657473;3731.4962480065387;3752.8498860045293;3755.4742336072973;3760.7401520490394;3777.205827232043;4074.103697186614;4287.785610719451;4292.613664770123;4299.075698724727;4308.89227769302;5008.1486147355035;5021.774020169101;5061.215511595708;5101.642884998088;5110.1568241239775;1224.4428784902848;1282.9871701282868;1292.5217669462359;1295.430010795247;1299.8505171905163;859.402715113003;884.2395539835736;887.8934527856699;893.6952050006932;901.4816480523594;4359.716618419792;4373.6282415994365;4406.184855801687;4413.801378937956;4449.2881139018245;5180.792768289301;5188.844334669966;5191;5196.68819870809;5198;4254.595813397722;4341.196878577473;4353.111144323246;4378.78106094694;4405.163019026375;2717.6331195288776;2725.4821583898997;2737.219892330676;2750.4499100179646;2750.6699196096597;4799.592034677022;4813.687110337817;4821.783019764119;4822.831200908022;4839.879003024882;5093.159628661304;5201;5235.764390602432;5244.108501554669;5277.683338999673;4770.308305295713;4775.2598347256235;4795.160846851787;4807.687500312539;4860.052289803454;5127.820526281562;5151.201563757623;5173.4826517347965;5184.507471790174;5207.27098206259;4906.460289368238;5025.723585202849;5035.949640503548;5038.697678139177;5073.16292811682;4824.927626085541;4825.8069677213925;4845.110406941366;4859.975700121477;4897.730624815669;3709.517762690833;3709.666130048309;3721.9069523262133;3724.832382542738;3728.1425272187007;5057.620678449204;5096.23556466533;5139.922901156411;5145.279660847559;5226.163813789775;4941.382327209099;4979.228219625964;4982.551570358685;4991.401031876053;4993.600511959107;4979.850604481871;4986.975065124651;4988.900221995613;5028.597712182944;5034.194528875362;4611.193041217841;4740.78666460017;4855.223164293696;4860.198067318853;4886.65793394459;5065.745743144265;5066.214736715815;5093.592512599057;5096.388433388017;5098.821541246187;4984.800607975641;5004.871955028108;5005.524475174852;5014.4484106747495;5024.949750502448 16:11:00 INFO - PROCESS | 6783 | |5;yui.html;615.5229696984836;738.8300690841103;762.2986852096072;765.2729906588742;771.7221800151945;11918.940405297972;12360.752784944296;12372.752544949095;12576.245425274466;12591.811122833165;11989.100817438682;12231.571894983677;12235.571754988578;12240.693982650439;12264.141510094298;630.3444417805476;778.6846327237478;779.7894568466511;799.9880001799949;800.747764454198;799.0810567846964;808.6724876425028;809.1382677448528;809.2069771623812;809.5102463009882;843.7342237195295;848.1137211613878;848.3849209323253;848.5078654380476;848.766589187972;647.9870402591922;811.6712731343756;837.6523742646795;842.9915700843005;843.4306842881031;610.1640752169512;610.6031079798166;612.1643956000072;612.5589575505629;613.524518498163;12013.519459221621;12025.57910473138;12057.93971030148;12062.45718942641;12064.638060858188;11715.53137874484;12138.939305303506;12226.510939562495;12274.202176858566;12494.439084860862;11025.393603351893;11034.558617655284;11069.335839849555;11309.717257068556;11470.197086203969;3973.9006524836823;3988.222296652154;4007.3988901664984;4058.4723985881647;4116.58834116589;43236.13527729428;43350.132997340195;43404.34893476601;43649.34525982113;43654.563454365365;9610.383176875663;9768.36505627132;9813.675153854281;9852;9925.106740393256;17532.737008944878;17540.47378578619;17580.560485987953;17615.031173285337;17616.823831761776;6652.8669426611195;6706.966465167643;6743.1908171019795;6771.424428923498;6784.525083244224;3694.9815250923575;3707.0917625182;3718.888433347004;3729.21686445844;3750.5986590038665;2743.43759529296;2812.1844665047315;2816.690164081949;2821.7319354661277;2827.872745726447;482.74897053531964;483.0387528817628;483.74603333250326;500.11978917106154;501.2381180605459;565.8161097643282;606.2300877885061;616.556079622671;641.9261784894796;657.0833687006694;7600.619969001527;7624.695012199561;7871.527708337518;8849.849025528436;9310.162085412345;647.3425147914322;654.3914159831307;668.301624802083;670.852412469256;673.5217995223348;3743.9438408423894;3776.8300426480873;3896.7467114637457;3916.4712763777097;4004.1791432756213;54107.18839217415;54342.72828635832;54752.45247547553;55037;55344.72327638417;48222.27666585004;48587.75706121447;48922.26616600754;49417.75291123521;50101.49898500968;44092.3386149208;44488.3326750099;44721;45018.77490612526;45451.99999999934;7278.417726581966;7367.852642947219;7379.188289288067;7434.7769566913075;7575.128860181149;29587.704122958494;30248.395032099674;31181.53227701541;31503.746994155495;31703.84148079245;38400.2319953605;40200.597994019685;40297.395539066354;40419.79790101031;40947.590524094376;10147.797044059225;10678.252522323673;10828.891711083104;10865.967733065347;11563.190576659554;11306.265092768943;11513.712157196138;11691.473883675086;12343.876561234272;12381.814272786096;11161.274517156684;11457;11469.426528673366;11494.885051149382;11567.248128871597;10957.56169753201;11537.90389912957;11560.30638161713;12320.876791231973;12323.938380308042;10675.46622668879;10960.342379456939;11258.09935205198;11476.13928955315;11508.59719909799;8877;8969.775755606162;9240.722778316662;9322.394044387092;9592.328537002342;9530.95234523823;9825.213982881496;9836.704898853046;9859.309848310837;9906.851397229191 16:11:00 INFO - PROCESS | 6783 | __end_tp_report 16:11:00 INFO - PROCESS | 6783 | __start_cc_report 16:11:00 INFO - PROCESS | 6783 | _x_x_mozilla_cycle_collect,1068 16:11:00 INFO - PROCESS | 6783 | __end_cc_report 16:11:00 INFO - PROCESS | 6783 | __startTimestamp1463785860180__endTimestamp 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | ------- Summary: start ------- 16:11:00 INFO - PROCESS | 6783 | Number of tests: 6 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#0] dojo.html Cycles:165 Average:4728.22 Median:4832.52 stddev:490.58 (10.2%) stddev-sans-first:465.85 16:11:00 INFO - PROCESS | 6783 | Values: 2710.4 2722.7 2739.4 2755.0 2776.8 4616.4 4692.1 4735.6 4840.4 4867.4 4689.0 4704.8 4710.7 4757.3 4778.5 4300.9 4346.2 4388.1 4421.4 4551.9 4471.2 4494.8 4499.1 4514.7 4558.3 4271.3 4316.1 4322.3 4356.3 4428.2 4349.3 4366.1 4370.6 4411.6 4412.6 4394.5 4410.9 4416.5 4416.5 4438.6 4524.1 4628.0 4672.2 4677.1 4766.5 5131.3 5185.3 5198.5 5201.2 5203.7 5183.1 5223.0 5235.2 5248.2 5249.7 4633.6 4739.4 4783.0 4793.7 4832.5 5336.3 5342.7 5350.5 5351.4 5355.9 5112.1 5117.2 5124.7 5126.7 5135.3 4904.6 5065.5 5136.0 5136.3 5150.5 5084.2 5111.5 5112.2 5116.6 5169.8 4885.6 5088.8 5097.5 5100.0 5101.5 4287.7 4307.5 4333.3 4337.1 4351.3 3795.2 3799.4 3833.6 3836.2 3878.0 4337.8 4345.4 4348.3 4392.8 4395.1 4892.8 4931.7 5089.0 5112.7 5128.8 4400.0 4405.1 4412.4 4435.8 4453.4 4652.0 4679.8 4692.3 4695.6 4742.7 4641.5 4723.8 4761.1 4785.7 4844.8 5151.2 5157.6 5160.5 5169.5 5213.0 4800.4 4801.9 4806.4 4811.4 4860.8 4888.1 4959.0 4972.2 5015.6 5020.7 5066.5 5090.4 5095.4 5097.9 5139.1 4780.2 4932.7 4939.9 4943.0 4948.3 4916.6 4950.4 4986.7 4990.4 5004.9 4907.7 4915.5 4959.1 4960.5 4962.8 4818.6 4919.9 5037.9 5083.5 5093.4 4911.0 4937.4 4939.2 4943.7 4989.0 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#1] ext.html Cycles:180 Average:42053.62 Median:20118.17 stddev:74990.44 (372.7%) stddev-sans-first:75136.07 16:11:00 INFO - PROCESS | 6783 | Values: 553.2 588.0 596.7 597.2 600.1 26837.7 29192.9 39470.4 40351.4 40489.6 40576.2 41027.4 41112.6 41142.8 41224.4 7797.6 7952.0 7968.2 7982.6 7986.6 7782.0 7906.3 7918.4 7919.0 7924.2 7906.9 7934.6 7947.0 7947.8 7951.8 7892.5 7903.3 7918.4 7923.1 7934.0 7852.8 7961.8 7970.4 7978.0 7993.9 38641.2 39178.0 39183.0 39211.6 39221.6 3011.2 3155.0 3254.0 3576.3 3624.0 27065.3 29465.4 29615.1 29840.0 30662.4 3459.6 3500.4 3500.8 3623.9 3668.5 254423.0 255023.0 255511.7 255578.0 256500.0 54303.0 55214.4 55309.0 55433.2 55510.4 34687.3 35431.3 35480.3 35490.1 35539.0 12866.7 13050.5 13075.5 13107.5 13107.8 9798.7 9922.0 9926.2 9933.4 9935.4 18547.5 18591.9 18794.8 18963.7 19021.9 6904.8 6915.4 6968.9 7032.2 7161.1 809.6 879.5 965.3 975.9 1012.7 27122.3 27973.7 28415.4 28634.7 28764.1 954.1 954.1 965.7 976.7 977.9 14823.7 15082.6 15092.4 15277.4 15444.0 392034.0 396159.0 397374.0 397783.0 399566.0 48918.5 49296.8 49401.0 49430.0 49448.0 1094.4 1164.3 1190.0 1206.7 1209.8 28275.3 28493.0 29035.4 29278.3 29292.0 41229.2 41242.6 41752.0 41849.8 41854.6 133688.0 133689.3 133998.0 134290.0 134377.0 22196.2 24753.9 25163.2 25201.5 25413.6 40942.4 41314.2 41488.2 41634.6 42063.6 35839.3 36075.5 36294.8 36567.8 36799.8 35158.5 35366.3 35702.3 35770.6 35943.0 34846.8 35679.6 35934.6 36023.1 36118.3 30631.8 31806.2 32455.0 32547.2 32569.7 24387.7 25202.9 25263.4 25300.6 25443.4 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#2] jquery.html Cycles:180 Average:24347.27 Median:46202.27 stddev:66330.23 (143.6%) stddev-sans-first:66491.86 16:11:00 INFO - PROCESS | 6783 | Values: 362.2 367.1 369.6 374.5 375.1 15812.1 17041.4 17160.4 17462.0 17599.2 16155.1 16575.8 17168.2 17275.6 17297.3 5007.3 5203.3 5203.8 5205.6 5215.2 3722.7 3884.7 5093.0 5683.0 6018.8 6058.5 6061.8 6098.2 6098.8 6106.8 5875.2 5914.8 5957.5 5969.4 6030.5 3386.6 4901.5 5216.2 5216.7 5274.1 19808.6 20512.0 20583.7 20613.9 20623.9 1980.2 2083.6 2164.8 2513.9 2523.6 12741.7 12805.3 12966.9 13188.3 13605.7 1754.5 1755.8 1777.4 1809.1 1820.4 66696.7 66812.0 66823.7 67081.7 67160.0 9355.9 9461.6 9570.4 9742.9 10164.2 13343.2 13348.9 13432.8 13448.9 13467.4 6638.4 6740.1 6865.3 6898.3 6900.9 6376.2 6551.9 6553.0 6701.7 6727.9 6052.7 6161.8 6248.9 6278.2 6348.1 1665.2 1704.5 1743.3 1755.1 1781.8 836.8 881.9 950.0 954.4 960.5 16469.1 16955.4 17152.0 17214.0 17605.9 902.9 924.1 929.3 930.2 938.9 9604.1 9873.8 9923.6 9949.7 10014.7 393796.0 403269.0 403965.0 406038.0 412677.0 67962.3 68882.0 69330.0 69730.7 70201.6 940.4 949.9 950.8 951.6 972.7 15056.6 15182.4 15185.8 15392.7 15706.8 33377.7 33816.7 34027.1 34244.0 34489.1 45760.0 46644.5 47325.3 47413.3 47541.3 7447.0 7503.7 7701.4 7872.0 7997.6 9989.4 10777.5 11331.0 11751.3 11899.2 9090.0 9788.5 10120.1 10179.2 10492.3 9889.3 10007.4 10077.6 10083.4 10114.2 9694.6 10130.8 10150.2 10339.5 10601.4 7794.4 7807.2 7840.0 7919.5 8228.8 10095.5 10166.1 10488.2 10652.9 10698.7 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#3] mootools.html Cycles:180 Average:5153.26 Median:5538.86 stddev:2881.85 (52.0%) stddev-sans-first:2875.15 16:11:00 INFO - PROCESS | 6783 | Values: 1269.2 1392.4 1431.1 1515.6 1725.8 6753.7 7399.1 7713.3 7719.7 7738.3 7325.3 7608.7 7613.7 7790.3 7791.5 2199.6 2218.6 2247.1 2253.1 2253.2 2176.7 2180.9 2238.4 2239.5 2239.9 2213.7 2218.2 2219.7 2229.3 2251.1 2134.8 2141.7 2168.5 2169.4 2169.8 2172.1 2192.8 2194.8 2198.0 2198.1 7386.0 7699.7 7718.9 7761.5 7773.4 4386.7 4474.8 4475.3 4487.5 4493.1 2788.3 2819.5 2845.9 2876.9 2882.7 892.2 918.9 919.5 928.7 929.9 10394.4 10551.2 10659.7 10660.2 10662.7 4134.4 4207.9 4276.9 4293.0 4306.5 8979.1 9022.4 9050.0 9079.3 9087.1 4404.1 4457.0 4465.0 4472.1 4480.1 3520.7 3912.4 4030.0 4039.9 4102.0 3644.0 3691.2 3694.5 3748.7 3792.2 1176.9 1209.8 1210.1 1221.4 1227.9 716.7 813.5 838.4 840.3 862.8 5189.4 5204.5 5398.6 5522.3 5601.4 817.0 885.2 898.0 906.5 921.3 3796.8 3822.6 3847.6 3960.3 3991.1 9725.9 9911.3 9917.8 9939.0 9953.8 9717.0 9755.8 9909.3 9933.0 9939.4 7283.1 7437.4 7452.8 7470.5 7473.3 5228.8 5513.4 5555.5 5615.6 5626.5 8796.6 8913.6 8988.6 9107.1 9129.3 8530.0 9051.5 9276.8 9289.4 9307.5 6146.5 6386.6 6570.4 6620.4 6768.5 7479.4 7517.6 7572.5 7588.0 7594.9 6572.3 6682.9 6694.2 6757.5 6983.3 6616.9 6671.7 6728.4 6814.0 6833.2 6173.0 6275.2 6360.3 6367.9 6369.7 4616.1 4647.7 4691.5 4712.6 4730.6 4537.6 4635.4 4658.0 4669.0 4727.6 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#4] prototype.html Cycles:180 Average:4177.73 Median:4772.78 stddev:1198.79 (25.1%) stddev-sans-first:1165.94 16:11:00 INFO - PROCESS | 6783 | Values: 281.2 286.8 295.4 301.6 304.5 4821.4 4831.4 4838.7 4849.2 4854.4 4536.8 4603.0 4649.3 4784.3 4800.6 3819.2 3850.9 3864.1 3865.4 3868.4 3830.0 3835.1 3851.1 3860.4 3872.2 3589.2 3676.4 3791.4 3799.7 3800.6 3699.5 3743.3 3776.3 3778.3 3794.9 3364.6 3384.9 3385.2 3400.5 3412.6 4376.7 4665.4 4680.4 4685.8 4704.5 4263.0 4284.4 4297.8 4310.4 4317.4 4972.8 5041.4 5051.0 5051.1 5068.9 3601.3 3603.2 3603.4 3651.4 3667.9 5034.0 5290.6 5293.2 5293.7 5357.8 3638.9 3681.9 3687.6 3703.6 3709.9 3731.5 3752.8 3755.5 3760.7 3777.2 4074.1 4287.8 4292.6 4299.1 4308.9 5008.1 5021.8 5061.2 5101.6 5110.2 1224.4 1283.0 1292.5 1295.4 1299.9 859.4 884.2 887.9 893.7 901.5 4359.7 4373.6 4406.2 4413.8 4449.3 5180.8 5188.8 5191.0 5196.7 5198.0 4254.6 4341.2 4353.1 4378.8 4405.2 2717.6 2725.5 2737.2 2750.4 2750.7 4799.6 4813.7 4821.8 4822.8 4839.9 5093.2 5201.0 5235.8 5244.1 5277.7 4770.3 4775.3 4795.2 4807.7 4860.1 5127.8 5151.2 5173.5 5184.5 5207.3 4906.5 5025.7 5035.9 5038.7 5073.2 4824.9 4825.8 4845.1 4860.0 4897.7 3709.5 3709.7 3721.9 3724.8 3728.1 5057.6 5096.2 5139.9 5145.3 5226.2 4941.4 4979.2 4982.6 4991.4 4993.6 4979.9 4987.0 4988.9 5028.6 5034.2 4611.2 4740.8 4855.2 4860.2 4886.7 5065.7 5066.2 5093.6 5096.4 5098.8 4984.8 5004.9 5005.5 5014.4 5024.9 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - PROCESS | 6783 | [#5] yui.html Cycles:180 Average:13037.19 Median:44290.34 stddev:14867.91 (33.6%) stddev-sans-first:14880.35 16:11:00 INFO - PROCESS | 6783 | Values: 615.5 738.8 762.3 765.3 771.7 11918.9 12360.8 12372.8 12576.2 12591.8 11989.1 12231.6 12235.6 12240.7 12264.1 630.3 778.7 779.8 800.0 800.7 799.1 808.7 809.1 809.2 809.5 843.7 848.1 848.4 848.5 848.8 648.0 811.7 837.7 843.0 843.4 610.2 610.6 612.2 612.6 613.5 12013.5 12025.6 12057.9 12062.5 12064.6 11715.5 12138.9 12226.5 12274.2 12494.4 11025.4 11034.6 11069.3 11309.7 11470.2 3973.9 3988.2 4007.4 4058.5 4116.6 43236.1 43350.1 43404.3 43649.3 43654.6 9610.4 9768.4 9813.7 9852.0 9925.1 17532.7 17540.5 17580.6 17615.0 17616.8 6652.9 6707.0 6743.2 6771.4 6784.5 3695.0 3707.1 3718.9 3729.2 3750.6 2743.4 2812.2 2816.7 2821.7 2827.9 482.7 483.0 483.7 500.1 501.2 565.8 606.2 616.6 641.9 657.1 7600.6 7624.7 7871.5 8849.8 9310.2 647.3 654.4 668.3 670.9 673.5 3743.9 3776.8 3896.7 3916.5 4004.2 54107.2 54342.7 54752.5 55037.0 55344.7 48222.3 48587.8 48922.3 49417.8 50101.5 44092.3 44488.3 44721.0 45018.8 45452.0 7278.4 7367.9 7379.2 7434.8 7575.1 29587.7 30248.4 31181.5 31503.7 31703.8 38400.2 40200.6 40297.4 40419.8 40947.6 10147.8 10678.3 10828.9 10866.0 11563.2 11306.3 11513.7 11691.5 12343.9 12381.8 11161.3 11457.0 11469.4 11494.9 11567.2 10957.6 11537.9 11560.3 12320.9 12323.9 10675.5 10960.3 11258.1 11476.1 11508.6 8877.0 8969.8 9240.7 9322.4 9592.3 9531.0 9825.2 9836.7 9859.3 9906.9 16:11:00 INFO - PROCESS | 6783 | -------- Summary: end -------- 16:11:00 INFO - PROCESS | 6783 | 16:11:00 INFO - TEST-INFO | 6783: exit 0 16:11:00 INFO - TEST-OK | dromaeo_css | took 1118873ms 16:11:00 INFO - TEST-START | kraken 16:11:00 INFO - operating with platform_type : linux_ 16:11:00 INFO - Initialising browser for kraken test... 16:11:00 INFO - TEST-INFO | started process 6958 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmT7C5i/profile http://localhost:41145/getInfo.html) 16:11:05 INFO - PROCESS | 6958 | __metrics Screen width/height:1600/1200 16:11:05 INFO - PROCESS | 6958 | colorDepth:24 16:11:05 INFO - PROCESS | 6958 | Browser inner width/height: 1024/697 16:11:05 INFO - PROCESS | 6958 | __metrics 16:11:06 ERROR - PROCESS | 6958 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:11:07 INFO - TEST-INFO | 6958: exit 0 16:11:07 INFO - Browser initialized. 16:11:07 INFO - Running cycle 1/1 for kraken test... 16:11:07 INFO - TEST-INFO | started process 7039 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmT7C5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:19 INFO - PROCESS | 7039 | RSS: Main: 154816512 16:11:19 INFO - PROCESS | 7039 | 16:11:21 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41145/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:22 INFO - PROCESS | 7039 | RSS: Main: 210362368 16:11:22 INFO - PROCESS | 7039 | 16:11:25 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41145/tests/kraken/driver.html?testName=audio-dft) 16:11:26 INFO - PROCESS | 7039 | RSS: Main: 198836224 16:11:26 INFO - PROCESS | 7039 | 16:11:29 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41145/tests/kraken/driver.html?testName=audio-fft) 16:11:30 INFO - PROCESS | 7039 | RSS: Main: 267792384 16:11:30 INFO - PROCESS | 7039 | 16:11:32 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41145/tests/kraken/driver.html?testName=audio-oscillator) 16:11:33 INFO - PROCESS | 7039 | RSS: Main: 176521216 16:11:33 INFO - PROCESS | 7039 | 16:11:35 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:36 INFO - PROCESS | 7039 | RSS: Main: 180686848 16:11:36 INFO - PROCESS | 7039 | 16:11:39 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41145/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:40 INFO - PROCESS | 7039 | RSS: Main: 216117248 16:11:40 INFO - PROCESS | 7039 | 16:11:43 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41145/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:44 INFO - PROCESS | 7039 | RSS: Main: 215220224 16:11:44 INFO - PROCESS | 7039 | 16:11:47 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41145/tests/kraken/driver.html?testName=json-parse-financial) 16:11:48 INFO - PROCESS | 7039 | RSS: Main: 215187456 16:11:48 INFO - PROCESS | 7039 | 16:11:50 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:50 INFO - PROCESS | 7039 | RSS: Main: 208863232 16:11:50 INFO - PROCESS | 7039 | 16:11:52 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:53 INFO - PROCESS | 7039 | RSS: Main: 212750336 16:11:53 INFO - PROCESS | 7039 | 16:11:55 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:56 INFO - PROCESS | 7039 | RSS: Main: 216633344 16:11:56 INFO - PROCESS | 7039 | 16:11:59 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:59 INFO - PROCESS | 7039 | RSS: Main: 221024256 16:11:59 INFO - PROCESS | 7039 | 16:12:02 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:12:03 INFO - PROCESS | 7039 | RSS: Main: 221011968 16:12:03 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | Cycle 1(1): loaded http://localhost:41145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41145/tests/kraken/driver.html?testName=ai-astar) 16:12:05 INFO - PROCESS | 7039 | RSS: Main: 266989568 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | __start_tp_report 16:12:05 INFO - PROCESS | 7039 | _x_x_mozilla_page_load 16:12:05 INFO - PROCESS | 7039 | _x_x_mozilla_page_load_details 16:12:05 INFO - PROCESS | 7039 | |i|pagename|runs| 16:12:05 INFO - PROCESS | 7039 | |0;ai-astar;114;112;116;114;114;114;114;113;114;113 16:12:05 INFO - PROCESS | 7039 | |1;audio-beat-detection;159;132;133;211;132;158;152;130;181;135 16:12:05 INFO - PROCESS | 7039 | |2;audio-dft;162;161;161;162;161;162;164;163;162;162 16:12:05 INFO - PROCESS | 7039 | |3;audio-fft;77;76;75;139;82;77;76;77;124;75 16:12:05 INFO - PROCESS | 7039 | |4;audio-oscillator;94;94;167;94;94;94;122;93;94;133 16:12:05 INFO - PROCESS | 7039 | |5;imaging-gaussian-blur;117;117;117;118;117;119;116;118;117;118 16:12:05 INFO - PROCESS | 7039 | |6;imaging-darkroom;147;146;146;146;147;147;148;147;147;147 16:12:05 INFO - PROCESS | 7039 | |7;imaging-desaturate;94;96;95;95;94;95;96;95;95;95 16:12:05 INFO - PROCESS | 7039 | |8;json-parse-financial;64;64;65;64;64;64;64;66;64;64 16:12:05 INFO - PROCESS | 7039 | |9;json-stringify-tinderbox;66;66;65;65;66;65;65;65;66;65 16:12:05 INFO - PROCESS | 7039 | |10;stanford-crypto-aes;82;83;82;82;82;83;83;82;142;84 16:12:05 INFO - PROCESS | 7039 | |11;stanford-crypto-ccm;138;131;130;129;130;129;129;130;146;133 16:12:05 INFO - PROCESS | 7039 | |12;stanford-crypto-pbkdf2;171;172;172;172;171;170;171;174;172;168 16:12:05 INFO - PROCESS | 7039 | |13;stanford-crypto-sha256-iterative;67;69;67;71;68;67;72;66;66;65 16:12:05 INFO - PROCESS | 7039 | __end_tp_report 16:12:05 INFO - PROCESS | 7039 | __start_cc_report 16:12:05 INFO - PROCESS | 7039 | _x_x_mozilla_cycle_collect,2098 16:12:05 INFO - PROCESS | 7039 | __end_cc_report 16:12:05 INFO - PROCESS | 7039 | __startTimestamp1463785925350__endTimestamp 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | ------- Summary: start ------- 16:12:05 INFO - PROCESS | 7039 | Number of tests: 14 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:1.09 16:12:05 INFO - PROCESS | 7039 | Values: 114.0 112.0 116.0 114.0 114.0 114.0 114.0 113.0 114.0 113.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#1] audio-beat-detection Cycles:10 Average:152.30 Median:155.00 stddev:26.54 (17.1%) stddev-sans-first:28.04 16:12:05 INFO - PROCESS | 7039 | Values: 159.0 132.0 133.0 211.0 132.0 158.0 152.0 130.0 181.0 135.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 16:12:05 INFO - PROCESS | 7039 | Values: 162.0 161.0 161.0 162.0 161.0 162.0 164.0 163.0 162.0 162.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#3] audio-fft Cycles:10 Average:87.80 Median:76.50 stddev:23.38 (30.6%) stddev-sans-first:24.47 16:12:05 INFO - PROCESS | 7039 | Values: 77.0 76.0 75.0 139.0 82.0 77.0 76.0 77.0 124.0 75.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#4] audio-oscillator Cycles:10 Average:107.90 Median:94.00 stddev:25.17 (26.8%) stddev-sans-first:26.19 16:12:05 INFO - PROCESS | 7039 | Values: 94.0 94.0 167.0 94.0 94.0 94.0 122.0 93.0 94.0 133.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 16:12:05 INFO - PROCESS | 7039 | Values: 117.0 117.0 117.0 118.0 117.0 119.0 116.0 118.0 117.0 118.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:12:05 INFO - PROCESS | 7039 | Values: 147.0 146.0 146.0 146.0 147.0 147.0 148.0 147.0 147.0 147.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 16:12:05 INFO - PROCESS | 7039 | Values: 94.0 96.0 95.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 16:12:05 INFO - PROCESS | 7039 | Values: 64.0 64.0 65.0 64.0 64.0 64.0 64.0 66.0 64.0 64.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 16:12:05 INFO - PROCESS | 7039 | Values: 66.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 66.0 65.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:82.50 stddev:18.81 (22.8%) stddev-sans-first:19.80 16:12:05 INFO - PROCESS | 7039 | Values: 82.0 83.0 82.0 82.0 82.0 83.0 83.0 82.0 142.0 84.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:130.50 stddev:5.48 (4.2%) stddev-sans-first:5.44 16:12:05 INFO - PROCESS | 7039 | Values: 138.0 131.0 130.0 129.0 130.0 129.0 129.0 130.0 146.0 133.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:172.00 stddev:1.57 (0.9%) stddev-sans-first:1.66 16:12:05 INFO - PROCESS | 7039 | Values: 171.0 172.0 172.0 172.0 171.0 170.0 171.0 174.0 172.0 168.0 16:12:05 INFO - PROCESS | 7039 | 16:12:05 INFO - PROCESS | 7039 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:2.25 (3.3%) stddev-sans-first:2.37 16:12:05 INFO - PROCESS | 7039 | Values: 67.0 69.0 67.0 71.0 68.0 67.0 72.0 66.0 66.0 65.0 16:12:05 INFO - PROCESS | 7039 | -------- Summary: end -------- 16:12:05 INFO - PROCESS | 7039 | 16:12:06 INFO - TEST-INFO | 7039: exit 0 16:12:06 INFO - TEST-OK | kraken | took 65480ms 16:12:06 INFO - SUITE-END | took 1184s 16:12:06 INFO - Completed test suite (00:19:44) 16:12:06 INFO - javascript benchmark 16:12:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2710.3901622135018, 2722.6868910075345, 2739.3973325868315, 2754.9724502754966, 2776.7778577713784, 4616.3767891334655, 4692.131955588218, 4735.597474214692, 4840.370751802269, 4867.367242258506, 4688.953110468894, 4704.811807527703, 4710.74090924999, 4757.262624293231, 4778.474367819537, 4300.913981720364, 4346.239408103585, 4388.056567837914, 4421.425214722081, 4551.8634440966825, 4471.172833025885, 4494.752788596626, 4499.1001799640035, 4514.729116253019, 4558.270676691714, 4271.295236286009, 4316.115196384733, 4322.286822674255, 4356.281213599752, 4428.202923473774, 4349.304111342201, 4366.104948485553, 4370.584794444522, 4411.580899814512, 4412.558744125594, 4394.472663280395, 4410.933835992462, 4416.492103408117, 4416.536054068613, 4438.644908407319, 4524.140413321458, 4628.0, 4672.229082201433, 4677.134730074947, 4766.499517550642, 5131.281620573122, 5185.299984502081, 5198.506141916512, 5201.193814958688, 5203.7398130093725, 5183.092958732207, 5223.0, 5235.214717792323, 5248.160294352885, 5249.685018898878, 4633.559811817872, 4739.360186374795, 4783.04339132168, 4793.736344501086, 4832.516748325174, 5336.306280183629, 5342.679439233659, 5350.5184533392185, 5351.384590772033, 5355.86610334737, 5112.130937740593, 5117.2068329409, 5124.666896651705, 5126.6923984561045, 5135.332406787168, 4904.55858972687, 5065.468125846806, 5135.974320128375, 5136.255242989746, 5150.510701483353, 5084.237364395371, 5111.539961403417, 5112.207607820719, 5116.599036898887, 5169.767360468788, 4885.60915126789, 5088.796448142107, 5097.541221290102, 5100.005498927748, 5101.464346243591, 4287.656987440997, 4307.547707490731, 4333.349997500401, 4337.132573485253, 4351.26028575143, 3795.221979494197, 3799.35410980134, 3833.5783063862955, 3836.156045669948, 3877.9612203878164, 4337.783110844445, 4345.435093437833, 4348.304271316638, 4392.824287028547, 4395.099004704028, 4892.804287828518, 4931.728754918514, 5089.0, 5112.74436278192, 5128.769205385766, 4399.956000440018, 4405.052913623586, 4412.404325416028, 4435.822567097344, 4453.354263631755, 4652.046330502239, 4679.766011699401, 4692.319613656001, 4695.577398034194, 4742.715437073786, 4641.535846415467, 4723.811047558197, 4761.095391875532, 4785.736784476887, 4844.75776211181, 5151.175811870083, 5157.561607263425, 5160.48395160481, 5169.4830516948, 5212.9739351304515, 4800.3759511263315, 4801.927971080367, 4806.447258565309, 4811.398575178103, 4860.781264843161, 4888.120138375127, 4958.950410495849, 4972.154733695208, 5015.623828212826, 5020.698758074527, 5066.4680208577565, 5090.389153301628, 5095.363079615048, 5097.898042039213, 5139.100657384868, 4780.235162374003, 4932.728699921395, 4939.9259011114145, 4942.980927244573, 4948.257761335828, 4916.582090522203, 4950.356453661001, 4986.700797952134, 4990.426100998432, 5004.899902002013, 4907.656464047483, 4915.483874193158, 4959.107360675113, 4960.528749768766, 4962.751862406937, 4818.638602104786, 4919.901601968012, 5037.899242015213, 5083.491650834887, 5093.439721630543, 4910.975445122752, 4937.35814344133, 4939.234418665113, 4943.728094954668, 4988.975055124701], "value": 4697.907608360501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1683706228081, 587.9970600146999, 596.73445316834, 597.2295738497338, 600.1208229943138, 26837.73162268377, 29192.85403572982, 39470.40794388079, 40351.39472907909, 40489.59510404895, 40576.188476230454, 41027.38458923111, 41112.58887411125, 41142.794286028606, 41224.381634275436, 7797.610119494031, 7952.0, 7968.203179682044, 7982.640781164832, 7986.560739159344, 7781.961090194541, 7906.327962123227, 7918.366530677532, 7918.960405197995, 7924.207579242087, 7906.920930790676, 7934.642941067637, 7947.0, 7947.761567152966, 7951.801204969865, 7892.486988345768, 7903.288704016609, 7918.4457088003865, 7923.12845586985, 7934.047914250327, 7852.764417067498, 7961.761147165595, 7970.402219833536, 7978.0, 7993.880091798627, 38641.22717545662, 39178.0, 39183.0, 39211.607883921366, 39221.60778392208, 3011.2170835582688, 3155.0, 3253.96746032539, 3576.302620988908, 3624.02151419118, 27065.32336691579, 29465.41069178626, 29615.111546653636, 29840.0, 30662.38675226483, 3459.6367381425093, 3500.352434799545, 3500.842462089212, 3623.855045798165, 3668.523091998023, 254423.0, 255023.0, 255511.72244138847, 255578.0, 256500.0, 54303.0, 55214.447855521736, 55309.0, 55433.168502472494, 55510.444895551336, 34687.30625387478, 35431.291374172375, 35480.29039419197, 35490.11274718153, 35539.0, 12866.678333041562, 13050.54323098687, 13075.476980920846, 13107.475700972045, 13107.803382949263, 9798.65704700332, 9921.950390248003, 9926.15627671656, 9933.3543319684, 9935.354201976992, 18547.536311592317, 18591.90704046471, 18794.812051879308, 18963.715544266848, 19021.90489047546, 6904.758333458305, 6915.4121899638085, 6968.895466568006, 7032.191298000693, 7161.140663120459, 809.6356639512126, 879.5382424227204, 965.3290962780941, 975.9072888075622, 1012.6607586458472, 27122.321941951213, 27973.72026279752, 28415.43169136606, 28634.713652863204, 28764.137075887338, 954.1269738189618, 954.1412728544227, 965.7006328038331, 976.6581696406201, 977.8777652793401, 14823.703525929637, 15082.622934426727, 15092.396304147931, 15277.38890444392, 15444.0, 392034.0, 396159.0, 397374.0, 397783.0, 399566.0, 48918.51081489139, 49296.75351623291, 49401.0, 49430.0, 49448.0, 1094.414488248783, 1164.2839653612966, 1189.9940500297616, 1206.6862615719976, 1209.8185272209064, 28275.293117672223, 28493.0, 29035.419291613627, 29278.26804329909, 29292.0, 41229.1754164909, 41242.58757412388, 41752.0, 41849.79075104605, 41854.581454186286, 133688.0, 133689.33155334162, 133998.0, 134290.0, 134377.0, 22196.223132190295, 24753.87623061873, 25163.24510264695, 25201.49597008086, 25413.61879571845, 40942.38586421266, 41314.1737165261, 41488.1702365957, 41634.583654164286, 42063.579364205965, 35839.28321433609, 36075.458868116475, 36294.8185259072, 36567.817160914026, 36799.816000919825, 35158.47262291119, 35366.29267414689, 35702.28595428129, 35770.64229357673, 35943.0, 34846.825765872025, 35679.64320356763, 35934.64065359313, 36023.09942251465, 36118.27763444664, 30631.846840765655, 31806.204844879063, 32455.0, 32547.18632034218, 32569.674303256663, 24387.658678772837, 25202.87398563069, 25263.36841578975, 25300.620490692287, 25443.36391590225], "value": 16908.43706452305, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.2121884900342, 367.0969415238513, 369.57683452446946, 374.5075872647501, 375.1109869609024, 15812.130332831704, 17041.403550875722, 17160.39938602149, 17462.0, 17599.20803563841, 16155.111468869207, 16575.751363729552, 17168.22742976566, 17275.568110797238, 17297.30810767568, 5007.2739452779415, 5203.349581302337, 5203.817866374678, 5205.583553315726, 5215.243789650498, 3722.702183825301, 3884.7086468514835, 5092.974535127338, 5682.971585142089, 6018.7893423730175, 6058.454739073482, 6061.757529698807, 6098.237720284965, 6098.817035488943, 6106.786262480814, 5875.236219291507, 5914.792982245623, 5957.523398128161, 5969.403059694017, 6030.487408570277, 3386.6444023377685, 4901.460839307673, 5216.217567364888, 5216.739163041871, 5274.077036518594, 19808.603827923504, 20512.0, 20583.691244631344, 20613.896930515402, 20623.89688051565, 1980.23760852073, 2083.593699228651, 2164.848460607758, 2513.899444022237, 2523.608840629687, 12741.745165096647, 12805.295708736016, 12966.87033129666, 13188.274644894527, 13605.727885442237, 1754.5175076853968, 1755.7629719987765, 1777.369033992927, 1809.1225755508567, 1820.3719716697717, 66696.6665166676, 66812.0, 66823.66588167028, 67081.66459167721, 67160.0, 9355.859662105075, 9461.574229159703, 9570.425774453555, 9742.902570974342, 10164.237682173867, 13343.199408035549, 13348.933255333855, 13432.798508022388, 13448.865511344762, 13467.393967271497, 6638.402543770987, 6740.123783908159, 6865.313468653095, 6898.275681053417, 6900.861982760317, 6376.171097757262, 6551.868962620721, 6553.0, 6701.698423570951, 6727.93272067273, 6052.66710330936, 6161.815145545641, 6248.875022499525, 6278.246610406781, 6348.111264422984, 1665.1507731057247, 1704.514213449161, 1743.2678275124479, 1755.131210051033, 1781.7950935642307, 836.786619412046, 881.9470831750116, 949.9762505937407, 954.3891909178132, 960.4765402855321, 16469.094199818886, 16955.40656077056, 17152.0, 17214.0, 17605.911970440324, 902.9413088149249, 924.0910946695382, 929.2798081486902, 930.2232635749225, 938.8779458670466, 9604.135627793392, 9873.802523949624, 9923.553440095213, 9949.651762188289, 10014.699559013095, 393796.0, 403269.0, 403965.0, 406038.0, 412677.0, 67962.3203767956, 68882.0, 69330.0, 69730.65134674395, 70201.64899175472, 940.4028441939417, 949.9002604726543, 950.7813202963357, 951.5670369981503, 972.7470857577081, 15056.623584410478, 15182.392704291704, 15185.772213417029, 15392.692146157238, 15706.842931570538, 33377.66622333843, 33816.66183338135, 34027.14932126716, 34244.0, 34489.13777155491, 45760.0, 46644.53355466402, 47325.29012064753, 47413.28880066733, 47541.28688069613, 7446.962765186356, 7503.699852006077, 7701.383889288955, 7871.960640196991, 7997.560134192442, 9989.400635961865, 10777.461126943464, 11331.0, 11751.294922304689, 11899.226550274205, 9090.000099989129, 9788.461634610165, 10120.089191972758, 10179.236557258384, 10492.265541412027, 9889.26937785899, 10007.449590272314, 10077.596896124072, 10083.445410502492, 10114.241431892491, 9694.563744631367, 10130.79738405213, 10150.187984961332, 10339.483025848527, 10601.363918164934, 7794.376449884109, 7807.18024607408, 7840.0, 7919.524828510308, 8228.83542329162, 10095.545700443645, 10166.135878450414, 10488.160947124365, 10652.8934710655, 10698.679039628823], "value": 8332.334710697245, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.2130878855112, 1392.3525560614316, 1431.0698046269927, 1515.6286709756112, 1725.7842769653794, 6753.696083676234, 7399.149097853741, 7713.305802477776, 7719.691212351513, 7738.303552680258, 7325.3040961108745, 7608.657610407531, 7613.657385417656, 7790.337821285183, 7791.454598178129, 2199.604071267171, 2218.622834118196, 2247.0562363807157, 2253.076238742116, 2253.2001139595454, 2176.706144670465, 2180.8800515971616, 2238.418011317061, 2239.4513344230686, 2239.9328020159423, 2213.7232845894264, 2218.190360518407, 2219.7003404540424, 2229.320057382498, 2251.0770584060538, 2134.7758485359045, 2141.7108690326763, 2168.4578855286177, 2169.4359466538654, 2169.837262205333, 2172.1094351315883, 2192.802647761693, 2194.8244140468723, 2198.0328655391577, 2198.120751699317, 7386.0, 7699.730509432143, 7718.922810771876, 7761.45669803114, 7773.416993725436, 4386.6929314947965, 4474.7986340614425, 4475.283954567253, 4487.506374298824, 4493.056458143794, 2788.261110805638, 2819.4502072095947, 2845.942922758637, 2876.942461150786, 2882.7117288271215, 892.1791951404723, 918.8575770755543, 919.5310391700281, 928.6796055360958, 929.8884133903907, 10394.428306443142, 10551.15590752738, 10659.733506662318, 10660.200484963581, 10662.680119596425, 4134.441850350225, 4207.915841683149, 4276.914461710749, 4292.978535107304, 4306.461692288464, 8979.14698103679, 9022.368434209673, 9049.954750226338, 9079.319051071196, 9087.13672201153, 4404.097160082176, 4457.041736026705, 4464.99537604043, 4472.127935052634, 4480.103979204172, 3520.6655367740022, 3912.4326972588815, 4029.979850100731, 4039.899002524902, 4102.015516276072, 3643.9817800911355, 3691.1694868654877, 3694.464302676097, 3748.737588368788, 3792.222594368203, 1176.8940795328463, 1209.782239196953, 1210.0501106631427, 1221.4015132584968, 1227.9017678585692, 716.7168968257613, 813.4509206285852, 838.3502785341409, 840.2983508770245, 862.7929296968808, 5189.351331083614, 5204.4535323791215, 5398.568114550826, 5522.2544956431175, 5601.411851755588, 817.0, 885.2386947225381, 898.0256421782309, 906.4787747045475, 921.2767977137914, 3796.8101594920145, 3822.636849499349, 3847.5960024197057, 3960.2673505401017, 3991.1419044905483, 9725.854112188323, 9911.306208565331, 9917.752056198653, 9939.0, 9953.80092398148, 9717.0, 9755.756106097404, 9909.306348555532, 9933.0, 9939.360005598995, 7283.089613798275, 7437.405007599487, 7452.776416707507, 7470.477066605394, 7473.327400533978, 5228.843134705813, 5513.448655134454, 5555.472230138131, 5615.578831587565, 5626.549876009991, 8796.648134074565, 8913.598888049924, 8988.595513202052, 9107.08929107084, 9129.315301352557, 8530.0, 9051.54742262871, 9276.814463710823, 9289.396189247676, 9307.488088155216, 6146.477549408171, 6386.648734319657, 6570.441512471493, 6620.404163625297, 6768.492363072691, 7479.401647868266, 7517.586532740533, 7572.545647261182, 7588.0, 7594.924050759642, 6572.277049524453, 6682.899756503559, 6694.196696396463, 6757.526973111835, 6983.336583024605, 6616.867662646624, 6671.6997735103005, 6728.428083612947, 6813.965930170318, 6833.214180369122, 6173.043178307369, 6275.215598050244, 6360.268569114611, 6367.90448143269, 6369.745210191726, 4616.146012987608, 4647.674662773574, 4691.5308469152815, 4712.622990160848, 4730.574248317668, 4537.636989040803, 4635.351050852951, 4658.0, 4669.042846216449, 4727.550882666142], "value": 4155.252708090802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.1761538691634, 286.80927183423023, 295.42539760166477, 301.6425535740149, 304.47174152844815, 4821.445533763618, 4831.444383895853, 4838.733869637169, 4849.224124140138, 4854.441739199993, 4536.795844187011, 4602.9873427845905, 4649.349091127245, 4784.330193772875, 4800.615950723943, 3819.236152769457, 3850.942235866464, 3864.111254411487, 3865.381538953768, 3868.3810590305557, 3829.9617003829885, 3835.1179228777396, 3851.114243723945, 3860.4402361657544, 3872.244912242114, 3589.210373717778, 3676.375016247231, 3791.393377059671, 3799.6960243180615, 3800.5629352624387, 3699.463577781207, 3743.288775132716, 3776.339140650375, 3778.2985165606588, 3794.867179648713, 3364.5794275715534, 3384.9492257616157, 3385.221399078201, 3400.4527939787336, 3412.6416726243883, 4376.715513491613, 4665.393498845162, 4680.4149481314835, 4685.789139488731, 4704.529547045268, 4263.0, 4284.421603083575, 4297.78511074445, 4310.4266942566055, 4317.417148684919, 4972.801087956477, 5041.395032596112, 5050.974745126251, 5051.1413059779925, 5068.935523540061, 3601.2977469393477, 3603.1712706077487, 3603.40543810271, 3651.4157734762575, 3667.94498082529, 5033.974830125826, 5290.576753859682, 5293.179557168645, 5293.708846013467, 5357.8392648220615, 3638.872639457606, 3681.9079523012138, 3687.59436461989, 3703.6481534254203, 3709.86682657473, 3731.4962480065387, 3752.8498860045293, 3755.4742336072973, 3760.7401520490394, 3777.205827232043, 4074.103697186614, 4287.785610719451, 4292.613664770123, 4299.075698724727, 4308.89227769302, 5008.1486147355035, 5021.774020169101, 5061.215511595708, 5101.642884998088, 5110.1568241239775, 1224.4428784902848, 1282.9871701282868, 1292.5217669462359, 1295.430010795247, 1299.8505171905163, 859.402715113003, 884.2395539835736, 887.8934527856699, 893.6952050006932, 901.4816480523594, 4359.716618419792, 4373.6282415994365, 4406.184855801687, 4413.801378937956, 4449.2881139018245, 5180.792768289301, 5188.844334669966, 5191.0, 5196.68819870809, 5198.0, 4254.595813397722, 4341.196878577473, 4353.111144323246, 4378.78106094694, 4405.163019026375, 2717.6331195288776, 2725.4821583898997, 2737.219892330676, 2750.4499100179646, 2750.6699196096597, 4799.592034677022, 4813.687110337817, 4821.783019764119, 4822.831200908022, 4839.879003024882, 5093.159628661304, 5201.0, 5235.764390602432, 5244.108501554669, 5277.683338999673, 4770.308305295713, 4775.2598347256235, 4795.160846851787, 4807.687500312539, 4860.052289803454, 5127.820526281562, 5151.201563757623, 5173.4826517347965, 5184.507471790174, 5207.27098206259, 4906.460289368238, 5025.723585202849, 5035.949640503548, 5038.697678139177, 5073.16292811682, 4824.927626085541, 4825.8069677213925, 4845.110406941366, 4859.975700121477, 4897.730624815669, 3709.517762690833, 3709.666130048309, 3721.9069523262133, 3724.832382542738, 3728.1425272187007, 5057.620678449204, 5096.23556466533, 5139.922901156411, 5145.279660847559, 5226.163813789775, 4941.382327209099, 4979.228219625964, 4982.551570358685, 4991.401031876053, 4993.600511959107, 4979.850604481871, 4986.975065124651, 4988.900221995613, 5028.597712182944, 5034.194528875362, 4611.193041217841, 4740.78666460017, 4855.223164293696, 4860.198067318853, 4886.65793394459, 5065.745743144265, 5066.214736715815, 5093.592512599057, 5096.388433388017, 5098.821541246187, 4984.800607975641, 5004.871955028108, 5005.524475174852, 5014.4484106747495, 5024.949750502448], "value": 3796.64999858284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.5229696984836, 738.8300690841103, 762.2986852096072, 765.2729906588742, 771.7221800151945, 11918.940405297972, 12360.752784944296, 12372.752544949095, 12576.245425274466, 12591.811122833165, 11989.100817438682, 12231.571894983677, 12235.571754988578, 12240.693982650439, 12264.141510094298, 630.3444417805476, 778.6846327237478, 779.7894568466511, 799.9880001799949, 800.747764454198, 799.0810567846964, 808.6724876425028, 809.1382677448528, 809.2069771623812, 809.5102463009882, 843.7342237195295, 848.1137211613878, 848.3849209323253, 848.5078654380476, 848.766589187972, 647.9870402591922, 811.6712731343756, 837.6523742646795, 842.9915700843005, 843.4306842881031, 610.1640752169512, 610.6031079798166, 612.1643956000072, 612.5589575505629, 613.524518498163, 12013.519459221621, 12025.57910473138, 12057.93971030148, 12062.45718942641, 12064.638060858188, 11715.53137874484, 12138.939305303506, 12226.510939562495, 12274.202176858566, 12494.439084860862, 11025.393603351893, 11034.558617655284, 11069.335839849555, 11309.717257068556, 11470.197086203969, 3973.9006524836823, 3988.222296652154, 4007.3988901664984, 4058.4723985881647, 4116.58834116589, 43236.13527729428, 43350.132997340195, 43404.34893476601, 43649.34525982113, 43654.563454365365, 9610.383176875663, 9768.36505627132, 9813.675153854281, 9852.0, 9925.106740393256, 17532.737008944878, 17540.47378578619, 17580.560485987953, 17615.031173285337, 17616.823831761776, 6652.8669426611195, 6706.966465167643, 6743.1908171019795, 6771.424428923498, 6784.525083244224, 3694.9815250923575, 3707.0917625182, 3718.888433347004, 3729.21686445844, 3750.5986590038665, 2743.43759529296, 2812.1844665047315, 2816.690164081949, 2821.7319354661277, 2827.872745726447, 482.74897053531964, 483.0387528817628, 483.74603333250326, 500.11978917106154, 501.2381180605459, 565.8161097643282, 606.2300877885061, 616.556079622671, 641.9261784894796, 657.0833687006694, 7600.619969001527, 7624.695012199561, 7871.527708337518, 8849.849025528436, 9310.162085412345, 647.3425147914322, 654.3914159831307, 668.301624802083, 670.852412469256, 673.5217995223348, 3743.9438408423894, 3776.8300426480873, 3896.7467114637457, 3916.4712763777097, 4004.1791432756213, 54107.18839217415, 54342.72828635832, 54752.45247547553, 55037.0, 55344.72327638417, 48222.27666585004, 48587.75706121447, 48922.26616600754, 49417.75291123521, 50101.49898500968, 44092.3386149208, 44488.3326750099, 44721.0, 45018.77490612526, 45451.99999999934, 7278.417726581966, 7367.852642947219, 7379.188289288067, 7434.7769566913075, 7575.128860181149, 29587.704122958494, 30248.395032099674, 31181.53227701541, 31503.746994155495, 31703.84148079245, 38400.2319953605, 40200.597994019685, 40297.395539066354, 40419.79790101031, 40947.590524094376, 10147.797044059225, 10678.252522323673, 10828.891711083104, 10865.967733065347, 11563.190576659554, 11306.265092768943, 11513.712157196138, 11691.473883675086, 12343.876561234272, 12381.814272786096, 11161.274517156684, 11457.0, 11469.426528673366, 11494.885051149382, 11567.248128871597, 10957.56169753201, 11537.90389912957, 11560.30638161713, 12320.876791231973, 12323.938380308042, 10675.46622668879, 10960.342379456939, 11258.09935205198, 11476.13928955315, 11508.59719909799, 8877.0, 8969.775755606162, 9240.722778316662, 9322.394044387092, 9592.328537002342, 9530.95234523823, 9825.213982881496, 9836.704898853046, 9859.309848310837, 9906.851397229191], "value": 6023.739601756293, "unit": "score"}], "name": "dromaeo_css", "value": 6306.394678572169, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 116.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 132.0, 133.0, 211.0, 132.0, 158.0, 152.0, 130.0, 181.0, 135.0], "value": 152.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 161.0, 162.0, 164.0, 163.0, 162.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 139.0, 82.0, 77.0, 76.0, 77.0, 124.0, 75.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 167.0, 94.0, 94.0, 94.0, 122.0, 93.0, 94.0, 133.0], "value": 107.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 119.0, 116.0, 118.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 82.0, 82.0, 83.0, 83.0, 82.0, 142.0, 84.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 131.0, 130.0, 129.0, 130.0, 129.0, 129.0, 130.0, 146.0, 133.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 172.0, 171.0, 170.0, 171.0, 174.0, 172.0, 168.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 71.0, 68.0, 67.0, 72.0, 66.0, 66.0, 65.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1572.8, "alertThreshold": 2.0}]} 16:12:06 INFO - Return code: 0 16:12:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:12:06 ERROR - # TBPL SUCCESS # 16:12:06 INFO - Running post-action listener: _resource_record_post_action 16:12:06 INFO - ##### 16:12:06 INFO - ##### Finished run-tests step (success) 16:12:06 INFO - ##### 16:12:06 INFO - Running post-run listener: _resource_record_post_run 16:12:06 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 532959232; Read time: 0; Write time: 450688 16:12:06 INFO - TinderboxPrint: CPU usage
13.1% 16:12:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:12:06 INFO - TinderboxPrint: I/O write bytes / time
532,959,232 / 450,688 16:12:06 INFO - TinderboxPrint: CPU idle
8,329.9 (86.9%) 16:12:06 INFO - TinderboxPrint: CPU user
1,212.0 (12.6%) 16:12:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:12:06 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 227778560; Read time: 0; Write time: 252976 16:12:06 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 289157120; Read time: 0; Write time: 195988 16:12:07 INFO - Running post-run listener: _upload_blobber_files 16:12:07 INFO - Blob upload gear active. 16:12:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:07 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 16:12:07 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:12:07 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:12:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:12:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:08 INFO - (blobuploader) - INFO - Done attempting. 16:12:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:12:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:12:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:13 INFO - (blobuploader) - INFO - Done attempting. 16:12:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:12:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:12:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:14 INFO - (blobuploader) - INFO - Done attempting. 16:12:14 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:14 INFO - Return code: 0 16:12:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d51e5c109379b0d7411c96885bf252db1e92d9e4b13a4b644c22319e6b7a81bce846548ec7998df3ed11c786cb4dc658417ac6de5d5974a8863d537b0390c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd44e6c84cbb803d9ab973693309493d19fc82e6c81ba78dcf409dcf807af522532b4d3c273fd9f8cc8818e78d3ab89f80faf4b52973788f598e8eb1593aa998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0787f5e4855fc30d02d41ecf9b7823412804f6543bf0d353ab0fd4fa9e9dff1d32eadd972612a451d57ce4f85fced0bc2f06000b9a1b673fe6f2c4182c818600"} 16:12:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:14 INFO - Contents: 16:12:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d51e5c109379b0d7411c96885bf252db1e92d9e4b13a4b644c22319e6b7a81bce846548ec7998df3ed11c786cb4dc658417ac6de5d5974a8863d537b0390c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd44e6c84cbb803d9ab973693309493d19fc82e6c81ba78dcf409dcf807af522532b4d3c273fd9f8cc8818e78d3ab89f80faf4b52973788f598e8eb1593aa998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0787f5e4855fc30d02d41ecf9b7823412804f6543bf0d353ab0fd4fa9e9dff1d32eadd972612a451d57ce4f85fced0bc2f06000b9a1b673fe6f2c4182c818600"} 16:12:14 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:14 INFO - Copying logs to upload dir... 16:12:14 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1463.378036 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 23 secs) (at 2016-05-20 16:12:13.550803) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 16:12:13.555184) ========= 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=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d51e5c109379b0d7411c96885bf252db1e92d9e4b13a4b644c22319e6b7a81bce846548ec7998df3ed11c786cb4dc658417ac6de5d5974a8863d537b0390c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd44e6c84cbb803d9ab973693309493d19fc82e6c81ba78dcf409dcf807af522532b4d3c273fd9f8cc8818e78d3ab89f80faf4b52973788f598e8eb1593aa998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0787f5e4855fc30d02d41ecf9b7823412804f6543bf0d353ab0fd4fa9e9dff1d32eadd972612a451d57ce4f85fced0bc2f06000b9a1b673fe6f2c4182c818600"} build_url:https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011447 build_url: 'https://queue.taskcluster.net/v1/task/fnSvswvqQJ-hGOFrzfXMlA/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/Firefox-Non-PGO/sha512/d51e5c109379b0d7411c96885bf252db1e92d9e4b13a4b644c22319e6b7a81bce846548ec7998df3ed11c786cb4dc658417ac6de5d5974a8863d537b0390c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd44e6c84cbb803d9ab973693309493d19fc82e6c81ba78dcf409dcf807af522532b4d3c273fd9f8cc8818e78d3ab89f80faf4b52973788f598e8eb1593aa998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0787f5e4855fc30d02d41ecf9b7823412804f6543bf0d353ab0fd4fa9e9dff1d32eadd972612a451d57ce4f85fced0bc2f06000b9a1b673fe6f2c4182c818600"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 16:12:13.583196) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 16:12:13.583550) ========= 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=4300f881736b422476174874000001bc-1463773499.121239-699048575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 16:12:13.664454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 16:12:13.667812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 16:12:13.668118) ========= ========= Total master_lag: 0.18 =========