builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1463488095.77 results: success (0) buildid: 20160515203829 builduid: fd462b370e5544bdae0208c7f82b5fc7 revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.766315) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.766689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.766959) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.797976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.798322) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 05:28:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.5M=0s 2016-05-17 05:28:16 (66.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161820 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.974169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:15.974487) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019381 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:16.023802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:28:16.024186) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0be57e84807ce0853b2406de7ff6abb195ac898 --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 d0be57e84807ce0853b2406de7ff6abb195ac898 --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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 05:28:16,906 truncating revision to first 12 chars 2016-05-17 05:28:16,906 Setting DEBUG logging. 2016-05-17 05:28:16,906 attempt 1/10 2016-05-17 05:28:16,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0be57e84807?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 05:28:17,978 unpacking tar archive at: mozilla-central-d0be57e84807/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353993 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:28:17.391250) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:17.391586) ========= script_repo_revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:17.392013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:17.392290) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:28:17.400573) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-17 05:28:17.400882) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:28:18 INFO - MultiFileLogger online at 20160517 05:28:18 in /builds/slave/test 05:28:18 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 05:28:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:28:18 INFO - {'append_to_log': False, 05:28:18 INFO - 'base_work_dir': '/builds/slave/test', 05:28:18 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:28:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:28:18 INFO - 'branch': 'Firefox-Non-PGO', 05:28:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:18 INFO - 'config_files': ('talos/linux_config.py',), 05:28:18 INFO - 'default_actions': ('clobber', 05:28:18 INFO - 'read-buildbot-config', 05:28:18 INFO - 'download-and-extract', 05:28:18 INFO - 'populate-webroot', 05:28:18 INFO - 'create-virtualenv', 05:28:18 INFO - 'install', 05:28:18 INFO - 'run-tests'), 05:28:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:18 INFO - 'download_minidump_stackwalk': True, 05:28:18 INFO - 'download_symbols': 'ondemand', 05:28:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:18 INFO - 'installer_path': 'installer.exe', 05:28:18 INFO - 'log_level': 'info', 05:28:18 INFO - 'log_name': 'talos', 05:28:18 INFO - 'log_to_console': True, 05:28:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:28:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:28:18 INFO - 'opt_config_files': (), 05:28:18 INFO - 'pip_index': False, 05:28:18 INFO - 'sps_profile': False, 05:28:18 INFO - 'sps_profile_interval': 0, 05:28:18 INFO - 'suite': 'dromaeojs', 05:28:18 INFO - 'system_bits': '32', 05:28:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:18 INFO - 'title': 'talos-linux64-ix-068', 05:28:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:18 INFO - 'use_talos_json': True, 05:28:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:28:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:18 INFO - 'work_dir': 'build'} 05:28:18 INFO - ##### 05:28:18 INFO - ##### Running clobber step. 05:28:18 INFO - ##### 05:28:18 INFO - Running pre-action listener: _resource_record_pre_action 05:28:18 INFO - Running main action method: clobber 05:28:18 INFO - rmtree: /builds/slave/test/build 05:28:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:28:18 INFO - Running post-action listener: _resource_record_post_action 05:28:18 INFO - ##### 05:28:18 INFO - ##### Finished clobber step (success) 05:28:18 INFO - ##### 05:28:18 INFO - ##### 05:28:18 INFO - ##### Running read-buildbot-config step. 05:28:18 INFO - ##### 05:28:18 INFO - Running pre-action listener: _resource_record_pre_action 05:28:18 INFO - Running main action method: read_buildbot_config 05:28:18 INFO - Using buildbot properties: 05:28:18 INFO - { 05:28:18 INFO - "project": "", 05:28:18 INFO - "product": "firefox", 05:28:18 INFO - "script_repo_revision": "production", 05:28:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:28:18 INFO - "repository": "", 05:28:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:28:18 INFO - "buildid": "20160515203829", 05:28:18 INFO - "pgo_build": "False", 05:28:18 INFO - "basedir": "/builds/slave/test", 05:28:18 INFO - "buildnumber": 581, 05:28:18 INFO - "slavename": "talos-linux64-ix-068", 05:28:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:28:18 INFO - "platform": "ubuntu64_hw", 05:28:18 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 05:28:18 INFO - "branch": "mozilla-central", 05:28:18 INFO - "repo_path": "mozilla-central", 05:28:18 INFO - "revision": "d0be57e84807ce0853b2406de7ff6abb195ac898", 05:28:18 INFO - "stage_platform": "linux64", 05:28:18 INFO - "builduid": "fd462b370e5544bdae0208c7f82b5fc7", 05:28:18 INFO - "slavebuilddir": "test" 05:28:18 INFO - } 05:28:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:28:18 INFO - Running post-action listener: _resource_record_post_action 05:28:18 INFO - ##### 05:28:18 INFO - ##### Finished read-buildbot-config step (success) 05:28:18 INFO - ##### 05:28:18 INFO - ##### 05:28:18 INFO - ##### Running download-and-extract step. 05:28:18 INFO - ##### 05:28:18 INFO - Running pre-action listener: _resource_record_pre_action 05:28:18 INFO - Running main action method: download_and_extract 05:28:18 INFO - mkdir: /builds/slave/test/build/tests 05:28:18 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')]} 05:28:18 INFO - https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:28:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:28:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:28:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:21 INFO - Downloaded 1361 bytes. 05:28:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:28:21 INFO - Using the following test package requirements: 05:28:21 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:28:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:28:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'jsshell-linux-x86_64.zip'], 05:28:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:28:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:28:21 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:28:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:28:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:28:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:21 INFO - https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:28:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:28:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:28 INFO - Downloaded 22447955 bytes. 05:28:28 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'] 05:28:28 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 05:28:30 INFO - Return code: 0 05:28:30 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 05:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:30 INFO - https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:28:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:28:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:35 INFO - Downloaded 22447955 bytes. 05:28:35 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'] 05:28:35 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 05:28:37 INFO - Return code: 0 05:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:37 INFO - https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:28:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:38 INFO - Downloaded 10840194 bytes. 05:28:38 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'] 05:28:38 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 05:28:38 INFO - Return code: 0 05:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:38 INFO - https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:28:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:28:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:28:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCs880ZaSqitwvq6IEVjaQ/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 05:28:40 INFO - Downloaded 54746950 bytes. 05:28:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:28:40 INFO - mkdir: /builds/slave/test/properties 05:28:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:40 INFO - Contents: 05:28:40 INFO - build_url:https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:28:41 INFO - Running post-action listener: _resource_record_post_action 05:28:41 INFO - Running post-action listener: set_extra_try_arguments 05:28:41 INFO - ##### 05:28:41 INFO - ##### Finished download-and-extract step (success) 05:28:41 INFO - ##### 05:28:41 INFO - ##### 05:28:41 INFO - ##### Running populate-webroot step. 05:28:41 INFO - ##### 05:28:41 INFO - Running pre-action listener: _resource_record_pre_action 05:28:41 INFO - Running main action method: populate_webroot 05:28:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:41 INFO - '--tppagecycles', 05:28:41 INFO - '7'], 05:28:41 INFO - 'tests': ['tsvgm']}}, 05:28:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['damp', 'tps']}, 05:28:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'tests': ['damp', 'tps']}, 05:28:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['dromaeo_dom']}, 05:28:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['a11yr', 05:28:41 INFO - 'ts_paint', 05:28:41 INFO - 'tpaint', 05:28:41 INFO - 'sessionrestore', 05:28:41 INFO - 'sessionrestore_no_auto_restore', 05:28:41 INFO - 'tabpaint']}, 05:28:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['tsvgx', 05:28:41 INFO - 'tsvgr_opacity', 05:28:41 INFO - 'tart', 05:28:41 INFO - 'tscrollx', 05:28:41 INFO - 'cart']}, 05:28:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:41 INFO - 'tsvgr_opacity', 05:28:41 INFO - 'tart', 05:28:41 INFO - 'tscrollx', 05:28:41 INFO - 'cart']}, 05:28:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'talos_options': ['--disable-e10s'], 05:28:41 INFO - 'tests': ['tp5o']}, 05:28:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'tests': ['tp5o']}, 05:28:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'talos_options': ['--disable-e10s', 05:28:41 INFO - '--xperf_path', 05:28:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:41 INFO - 'tests': ['tp5n']}, 05:28:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:41 INFO - 'talos_options': ['--xperf_path', 05:28:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:41 INFO - 'tests': ['tp5n']}}, 05:28:41 INFO - 'talos.zip': {'path': '', 05:28:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:41 INFO - Running post-action listener: _resource_record_post_action 05:28:41 INFO - ##### 05:28:41 INFO - ##### Finished populate-webroot step (success) 05:28:41 INFO - ##### 05:28:41 INFO - ##### 05:28:41 INFO - ##### Running create-virtualenv step. 05:28:41 INFO - ##### 05:28:41 INFO - Running pre-action listener: _resource_record_pre_action 05:28:41 INFO - Running main action method: create_virtualenv 05:28:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:41 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 05:28:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:41 INFO - Using real prefix '/usr' 05:28:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:43 INFO - Installing pip.................done. 05:28:43 INFO - Return code: 0 05:28:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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')]} 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28: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 05:28: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 05:28:43 INFO - Using env: {'DISPLAY': ':0', 05:28:43 INFO - 'HOME': '/home/cltbld', 05:28:43 INFO - 'LANG': 'en_US.UTF-8', 05:28:43 INFO - 'LANGUAGE': 'en_US:en', 05:28:43 INFO - 'LOGNAME': 'cltbld', 05:28:43 INFO - 'MAIL': '/var/mail/cltbld', 05:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:43 INFO - 'MOZ_NO_REMOTE': '1', 05:28:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:43 INFO - 'NO_EM_RESTART': '1', 05:28:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:43 INFO - 'PWD': '/builds/slave/test', 05:28:43 INFO - 'SHELL': '/bin/bash', 05:28:43 INFO - 'SHLVL': '1', 05:28:43 INFO - 'TERM': 'linux', 05:28:43 INFO - 'TMOUT': '86400', 05:28:43 INFO - 'USER': 'cltbld', 05:28:43 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:43 INFO - '_': '/tools/buildbot/bin/python'} 05:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:44 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 05:28:44 INFO - Cleaning up... 05:28:44 INFO - Return code: 0 05:28:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28:44 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 05:28:44 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 05:28:44 INFO - Using env: {'DISPLAY': ':0', 05:28:44 INFO - 'HOME': '/home/cltbld', 05:28:44 INFO - 'LANG': 'en_US.UTF-8', 05:28:44 INFO - 'LANGUAGE': 'en_US:en', 05:28:44 INFO - 'LOGNAME': 'cltbld', 05:28:44 INFO - 'MAIL': '/var/mail/cltbld', 05:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:44 INFO - 'MOZ_NO_REMOTE': '1', 05:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:44 INFO - 'NO_EM_RESTART': '1', 05:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:44 INFO - 'PWD': '/builds/slave/test', 05:28:44 INFO - 'SHELL': '/bin/bash', 05:28:44 INFO - 'SHLVL': '1', 05:28:44 INFO - 'TERM': 'linux', 05:28:44 INFO - 'TMOUT': '86400', 05:28:44 INFO - 'USER': 'cltbld', 05:28:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:44 INFO - '_': '/tools/buildbot/bin/python'} 05:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:44 INFO - Downloading/unpacking psutil>=3.1.1 05:28:44 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 05:28:44 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 05:28:44 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 05:28:44 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 05:28:44 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 05:28:44 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 05:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:46 INFO - Installing collected packages: psutil 05:28:46 INFO - Running setup.py install for psutil 05:28:46 INFO - building 'psutil._psutil_linux' extension 05:28:46 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 05:28: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:28:46 INFO - building 'psutil._psutil_posix' extension 05:28:46 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 05:28: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 05:28:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:46 INFO - Successfully installed psutil 05:28:46 INFO - Cleaning up... 05:28:46 INFO - Return code: 0 05:28:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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')]} 05:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28: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 05:28: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 05:28:46 INFO - Using env: {'DISPLAY': ':0', 05:28:46 INFO - 'HOME': '/home/cltbld', 05:28:46 INFO - 'LANG': 'en_US.UTF-8', 05:28:46 INFO - 'LANGUAGE': 'en_US:en', 05:28:46 INFO - 'LOGNAME': 'cltbld', 05:28:46 INFO - 'MAIL': '/var/mail/cltbld', 05:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:46 INFO - 'MOZ_NO_REMOTE': '1', 05:28:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:46 INFO - 'NO_EM_RESTART': '1', 05:28:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:46 INFO - 'PWD': '/builds/slave/test', 05:28:46 INFO - 'SHELL': '/bin/bash', 05:28:46 INFO - 'SHLVL': '1', 05:28:46 INFO - 'TERM': 'linux', 05:28:46 INFO - 'TMOUT': '86400', 05:28:46 INFO - 'USER': 'cltbld', 05:28:46 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:46 INFO - '_': '/tools/buildbot/bin/python'} 05:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28: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 05:28: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 05:28: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 05:28: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 05:28: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 05:28: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 05:28:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:28:48 INFO - Installing collected packages: mozsystemmonitor 05:28:48 INFO - Running setup.py install for mozsystemmonitor 05:28:49 INFO - Successfully installed mozsystemmonitor 05:28:49 INFO - Cleaning up... 05:28:49 INFO - Return code: 0 05:28:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:49 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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28:49 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 05:28:49 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 05:28:49 INFO - Using env: {'DISPLAY': ':0', 05:28:49 INFO - 'HOME': '/home/cltbld', 05:28:49 INFO - 'LANG': 'en_US.UTF-8', 05:28:49 INFO - 'LANGUAGE': 'en_US:en', 05:28:49 INFO - 'LOGNAME': 'cltbld', 05:28:49 INFO - 'MAIL': '/var/mail/cltbld', 05:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:49 INFO - 'MOZ_NO_REMOTE': '1', 05:28:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:49 INFO - 'NO_EM_RESTART': '1', 05:28:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:49 INFO - 'PWD': '/builds/slave/test', 05:28:49 INFO - 'SHELL': '/bin/bash', 05:28:49 INFO - 'SHLVL': '1', 05:28:49 INFO - 'TERM': 'linux', 05:28:49 INFO - 'TMOUT': '86400', 05:28:49 INFO - 'USER': 'cltbld', 05:28:49 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:49 INFO - '_': '/tools/buildbot/bin/python'} 05:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:28:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 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 05:28:51 INFO - Downloading docopt-0.6.1.tar.gz 05:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:51 INFO - Running setup.py install for blobuploader 05:28:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:52 INFO - Running setup.py install for requests 05:28:52 INFO - Running setup.py install for docopt 05:28:52 INFO - Successfully installed blobuploader requests docopt 05:28:52 INFO - Cleaning up... 05:28:52 INFO - Return code: 0 05:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28:52 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 05:28:52 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 05:28:52 INFO - Using env: {'DISPLAY': ':0', 05:28:52 INFO - 'HOME': '/home/cltbld', 05:28:52 INFO - 'LANG': 'en_US.UTF-8', 05:28:52 INFO - 'LANGUAGE': 'en_US:en', 05:28:52 INFO - 'LOGNAME': 'cltbld', 05:28:52 INFO - 'MAIL': '/var/mail/cltbld', 05:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:52 INFO - 'MOZ_NO_REMOTE': '1', 05:28:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:52 INFO - 'NO_EM_RESTART': '1', 05:28:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:52 INFO - 'PWD': '/builds/slave/test', 05:28:52 INFO - 'SHELL': '/bin/bash', 05:28:52 INFO - 'SHLVL': '1', 05:28:52 INFO - 'TERM': 'linux', 05:28:52 INFO - 'TMOUT': '86400', 05:28:52 INFO - 'USER': 'cltbld', 05:28:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:52 INFO - '_': '/tools/buildbot/bin/python'} 05:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:52 INFO - Running setup.py (path:/tmp/pip-YQ3FJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:52 INFO - Running setup.py (path:/tmp/pip-chEGen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:52 INFO - Running setup.py (path:/tmp/pip-tPwyNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:52 INFO - Running setup.py (path:/tmp/pip-iFmNLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:53 INFO - Running setup.py (path:/tmp/pip-JdKOJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:53 INFO - Running setup.py (path:/tmp/pip-rnVDou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:53 INFO - Running setup.py (path:/tmp/pip-QAySmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:53 INFO - Running setup.py (path:/tmp/pip-aaFSOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:53 INFO - Running setup.py (path:/tmp/pip-HloYBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:53 INFO - Running setup.py (path:/tmp/pip-FUGzxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:53 INFO - Running setup.py (path:/tmp/pip-1EKehG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:53 INFO - Running setup.py (path:/tmp/pip-zK2oZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:53 INFO - Running setup.py (path:/tmp/pip-Fo31bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:53 INFO - Running setup.py (path:/tmp/pip-ELknln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:53 INFO - Running setup.py (path:/tmp/pip-qZ9p4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:53 INFO - Running setup.py (path:/tmp/pip-Ro8DQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:53 INFO - Running setup.py (path:/tmp/pip-DcbBoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:53 INFO - Running setup.py install for manifestparser 05:28:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Running setup.py install for mozcrash 05:28:54 INFO - Running setup.py install for mozdebug 05:28:54 INFO - Running setup.py install for mozdevice 05:28:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Running setup.py install for mozfile 05:28:54 INFO - Running setup.py install for mozhttpd 05:28:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Running setup.py install for mozinfo 05:28:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Running setup.py install for mozInstall 05:28:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:54 INFO - Running setup.py install for mozleak 05:28:54 INFO - Running setup.py install for mozlog 05:28:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Running setup.py install for moznetwork 05:28:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Running setup.py install for mozprocess 05:28:55 INFO - Running setup.py install for mozprofile 05:28:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Running setup.py install for mozrunner 05:28:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Running setup.py install for mozscreenshot 05:28:55 INFO - Running setup.py install for moztest 05:28:55 INFO - Running setup.py install for mozversion 05:28:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:55 INFO - Cleaning up... 05:28:55 INFO - Return code: 0 05:28:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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')]} 05:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28: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 05:28: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 05:28:55 INFO - Using env: {'DISPLAY': ':0', 05:28:55 INFO - 'HOME': '/home/cltbld', 05:28:55 INFO - 'LANG': 'en_US.UTF-8', 05:28:55 INFO - 'LANGUAGE': 'en_US:en', 05:28:55 INFO - 'LOGNAME': 'cltbld', 05:28:55 INFO - 'MAIL': '/var/mail/cltbld', 05:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:55 INFO - 'MOZ_NO_REMOTE': '1', 05:28:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:55 INFO - 'NO_EM_RESTART': '1', 05:28:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:55 INFO - 'PWD': '/builds/slave/test', 05:28:55 INFO - 'SHELL': '/bin/bash', 05:28:55 INFO - 'SHLVL': '1', 05:28:55 INFO - 'TERM': 'linux', 05:28:55 INFO - 'TMOUT': '86400', 05:28:55 INFO - 'USER': 'cltbld', 05:28:55 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:55 INFO - '_': '/tools/buildbot/bin/python'} 05:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:55 INFO - Running setup.py (path:/tmp/pip-u4JWL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:56 INFO - Running setup.py (path:/tmp/pip-F2bTQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:56 INFO - Running setup.py (path:/tmp/pip-jojpn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:56 INFO - Running setup.py (path:/tmp/pip-EoaAiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:56 INFO - Running setup.py (path:/tmp/pip-WCkq6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:56 INFO - Running setup.py (path:/tmp/pip-xlIRut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:56 INFO - Running setup.py (path:/tmp/pip-Oo_R4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:56 INFO - Running setup.py (path:/tmp/pip-r1UR74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:56 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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:56 INFO - Running setup.py (path:/tmp/pip-3zLOq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28: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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:56 INFO - Running setup.py (path:/tmp/pip-3Itget-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28: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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:56 INFO - Running setup.py (path:/tmp/pip-fon8NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28: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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:56 INFO - Running setup.py (path:/tmp/pip-w22QeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28: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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:56 INFO - Running setup.py (path:/tmp/pip-fAZm_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28: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)) 05:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:56 INFO - Running setup.py (path:/tmp/pip-B3Nj1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:57 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)) 05:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:57 INFO - Running setup.py (path:/tmp/pip-55L_f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:57 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)) 05:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:57 INFO - Running setup.py (path:/tmp/pip-txiBKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:57 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)) 05:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:57 INFO - Running setup.py (path:/tmp/pip-lz9VMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 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)) 05:28:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:59 INFO - Downloading blessings-1.6.tar.gz 05:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:59 INFO - Installing collected packages: blessings 05:28:59 INFO - Running setup.py install for blessings 05:28:59 INFO - Successfully installed blessings 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:59 INFO - Reading from file tmpfile_stdout 05:28:59 INFO - Current package versions: 05:28:59 INFO - argparse == 1.2.1 05:28:59 INFO - blessings == 1.6 05:28:59 INFO - blobuploader == 1.2.4 05:28:59 INFO - docopt == 0.6.1 05:28:59 INFO - manifestparser == 1.1 05:28:59 INFO - mozInstall == 1.12 05:28:59 INFO - mozcrash == 0.17 05:28:59 INFO - mozdebug == 0.1 05:28:59 INFO - mozdevice == 0.48 05:28:59 INFO - mozfile == 1.2 05:28:59 INFO - mozhttpd == 0.7 05:28:59 INFO - mozinfo == 0.9 05:28:59 INFO - mozleak == 0.1 05:28:59 INFO - mozlog == 3.2 05:28:59 INFO - moznetwork == 0.27 05:28:59 INFO - mozprocess == 0.22 05:28:59 INFO - mozprofile == 0.28 05:28:59 INFO - mozrunner == 6.11 05:28:59 INFO - mozscreenshot == 0.1 05:28:59 INFO - mozsystemmonitor == 0.3 05:28:59 INFO - moztest == 0.7 05:28:59 INFO - mozversion == 1.4 05:28:59 INFO - psutil == 3.1.1 05:28:59 INFO - requests == 1.2.3 05:28:59 INFO - wsgiref == 0.1.2 05:28:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28:59 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 05:28:59 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 05:28:59 INFO - Using env: {'DISPLAY': ':0', 05:28:59 INFO - 'HOME': '/home/cltbld', 05:28:59 INFO - 'LANG': 'en_US.UTF-8', 05:28:59 INFO - 'LANGUAGE': 'en_US:en', 05:28:59 INFO - 'LOGNAME': 'cltbld', 05:28:59 INFO - 'MAIL': '/var/mail/cltbld', 05:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:59 INFO - 'MOZ_NO_REMOTE': '1', 05:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:59 INFO - 'NO_EM_RESTART': '1', 05:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:59 INFO - 'PWD': '/builds/slave/test', 05:28:59 INFO - 'SHELL': '/bin/bash', 05:28:59 INFO - 'SHLVL': '1', 05:28:59 INFO - 'TERM': 'linux', 05:28:59 INFO - 'TMOUT': '86400', 05:28:59 INFO - 'USER': 'cltbld', 05:28:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:59 INFO - '_': '/tools/buildbot/bin/python'} 05:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28: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)) 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:28: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 05:28: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 05:28:59 INFO - Using env: {'DISPLAY': ':0', 05:28:59 INFO - 'HOME': '/home/cltbld', 05:28:59 INFO - 'LANG': 'en_US.UTF-8', 05:28:59 INFO - 'LANGUAGE': 'en_US:en', 05:28:59 INFO - 'LOGNAME': 'cltbld', 05:28:59 INFO - 'MAIL': '/var/mail/cltbld', 05:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:59 INFO - 'MOZ_NO_REMOTE': '1', 05:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:59 INFO - 'NO_EM_RESTART': '1', 05:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:59 INFO - 'PWD': '/builds/slave/test', 05:28:59 INFO - 'SHELL': '/bin/bash', 05:28:59 INFO - 'SHLVL': '1', 05:28:59 INFO - 'TERM': 'linux', 05:28:59 INFO - 'TMOUT': '86400', 05:28:59 INFO - 'USER': 'cltbld', 05:28:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:59 INFO - '_': '/tools/buildbot/bin/python'} 05:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:00 INFO - Downloading/unpacking jsonschema 05:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:01 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:02 INFO - warning: no files found matching '*.txt' 05:29:02 INFO - no previously-included directories found matching 'build' 05:29:02 INFO - no previously-included directories found matching 'dist' 05:29:02 INFO - no previously-included directories found matching '.git*' 05:29:02 INFO - Installing collected packages: jsonschema, functools32 05:29:02 INFO - Running setup.py install for functools32 05:29:02 INFO - warning: no files found matching '*.txt' 05:29:02 INFO - no previously-included directories found matching 'build' 05:29:02 INFO - no previously-included directories found matching 'dist' 05:29:02 INFO - no previously-included directories found matching '.git*' 05:29:02 INFO - Successfully installed jsonschema functools32 05:29:02 INFO - Cleaning up... 05:29:02 INFO - Return code: 0 05:29:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6b77981f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e0770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, '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': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', '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 05:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 INFO - Downloading/unpacking flake8 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:29:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:29:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:29:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:05 INFO - no previously-included directories found matching 'docs/_build' 05:29:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:29:05 INFO - Running setup.py install for pep8 05:29:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:05 INFO - no previously-included directories found matching 'docs/_build' 05:29:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:29:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:29:05 INFO - Cleaning up... 05:29:05 INFO - Return code: 0 05:29:05 INFO - Running post-action listener: _resource_record_post_action 05:29:05 INFO - Running post-action listener: _start_resource_monitoring 05:29:05 INFO - Starting resource monitoring. 05:29:05 INFO - ##### 05:29:05 INFO - ##### Finished create-virtualenv step (success) 05:29:05 INFO - ##### 05:29:05 INFO - ##### 05:29:05 INFO - ##### Running install step. 05:29:05 INFO - ##### 05:29:05 INFO - Running pre-action listener: _resource_record_pre_action 05:29:05 INFO - Running main action method: install 05:29:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:05 INFO - Reading from file tmpfile_stdout 05:29:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:29:05 INFO - Reading from file tmpfile_stdout 05:29:05 INFO - Output received: 05:29:05 INFO - Usage: mozinstall [options] installer 05:29:05 INFO - Options: 05:29:05 INFO - -h, --help show this help message and exit 05:29:05 INFO - -d DEST, --destination=DEST 05:29:05 INFO - Directory to install application into. [default: 05:29:05 INFO - "/builds/slave/test"] 05:29:05 INFO - --app=APP Application being installed. [default: firefox] 05:29:05 INFO - mkdir: /builds/slave/test/build/application 05:29: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'] 05:29: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 05:29:21 INFO - Reading from file tmpfile_stdout 05:29:21 INFO - Output received: 05:29:21 INFO - /builds/slave/test/build/application/firefox/firefox 05:29:21 INFO - Running post-action listener: _resource_record_post_action 05:29:21 INFO - ##### 05:29:21 INFO - ##### Finished install step (success) 05:29:21 INFO - ##### 05:29:21 INFO - ##### 05:29:21 INFO - ##### Running run-tests step. 05:29:21 INFO - ##### 05:29:21 INFO - Running pre-action listener: _resource_record_pre_action 05:29:21 INFO - Running main action method: run_tests 05:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:29:21 INFO - Python 2.7.3 05:29:21 INFO - Return code: 0 05:29:21 INFO - grabbing minidump binary from tooltool 05:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:21 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 0x276f9c0>, '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 0x27708c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2770d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:29:21 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 05:29:21 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 05:29:21 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 05:29:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:21 INFO - Return code: 0 05:29:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:29:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:29:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:29:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:29:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:29:22 INFO - Return code: 0 05:29:22 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/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:29:22 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/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 05:29:22 INFO - Using env: {'DISPLAY': ':0', 05:29:22 INFO - 'HOME': '/home/cltbld', 05:29:22 INFO - 'LANG': 'en_US.UTF-8', 05:29:22 INFO - 'LANGUAGE': 'en_US:en', 05:29:22 INFO - 'LOGNAME': 'cltbld', 05:29:22 INFO - 'MAIL': '/var/mail/cltbld', 05:29:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:29:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:22 INFO - 'MOZ_NO_REMOTE': '1', 05:29:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:22 INFO - 'NO_EM_RESTART': '1', 05:29:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:22 INFO - 'PWD': '/builds/slave/test', 05:29:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:29:22 INFO - 'SHELL': '/bin/bash', 05:29:22 INFO - 'SHLVL': '1', 05:29:22 INFO - 'TERM': 'linux', 05:29:22 INFO - 'TMOUT': '86400', 05:29:22 INFO - 'USER': 'cltbld', 05:29:22 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245', 05:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:22 INFO - '_': '/tools/buildbot/bin/python'} 05:29:22 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/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:29:22 INFO - mozversion application_buildid: 20160515203829 05:29:22 INFO - mozversion application_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:29:22 INFO - mozversion application_display_name: Nightly 05:29:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:22 INFO - mozversion application_name: Firefox 05:29:22 INFO - mozversion application_remotingname: firefox 05:29:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:29:22 INFO - mozversion application_vendor: Mozilla 05:29:22 INFO - mozversion application_version: 49.0a1 05:29:22 INFO - mozversion platform_buildid: 20160515203829 05:29:22 INFO - mozversion platform_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:29:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:29:22 INFO - mozversion platform_version: 49.0a1 05:29:22 INFO - using testdate: 1463488162 05:29:22 INFO - actual date: 1463488162 05:29:22 INFO - starting webserver on 'localhost:53134' 05:29:22 INFO - SUITE-START | Running 2 tests 05:29:22 INFO - TEST-START | dromaeo_css 05:29:22 INFO - operating with platform_type : linux_ 05:29:22 INFO - Initialising browser for dromaeo_css test... 05:29:22 INFO - TEST-INFO | started process 1784 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW4uLOK/profile http://localhost:53134/getInfo.html) 05:29:27 INFO - PROCESS | 1784 | __metrics Screen width/height:1600/1200 05:29:27 INFO - PROCESS | 1784 | colorDepth:24 05:29:27 INFO - PROCESS | 1784 | Browser inner width/height: 1024/697 05:29:27 INFO - PROCESS | 1784 | __metrics 05:29:28 ERROR - PROCESS | 1784 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:29:29 INFO - TEST-INFO | 1784: exit 0 05:29:29 INFO - Browser initialized. 05:29:29 INFO - Running cycle 1/1 for dromaeo_css test... 05:29:29 INFO - TEST-INFO | started process 1869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW4uLOK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:41 INFO - PROCESS | 1869 | RSS: Main: 154132480 05:29:41 INFO - PROCESS | 1869 | 05:30:32 INFO - PROCESS | 1869 | 1463488232928 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] 05:32:44 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53134/tests/dromaeo/cssquery-ext.html) 05:32:45 INFO - PROCESS | 1869 | RSS: Main: 254935040 05:32:45 INFO - PROCESS | 1869 | 05:35:48 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-ext.html (next: http://localhost:53134/tests/dromaeo/cssquery-jquery.html) 05:35:49 INFO - PROCESS | 1869 | RSS: Main: 237932544 05:35:49 INFO - PROCESS | 1869 | 05:38:51 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53134/tests/dromaeo/cssquery-mootools.html) 05:38:51 INFO - PROCESS | 1869 | RSS: Main: 238481408 05:38:51 INFO - PROCESS | 1869 | 05:41:54 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53134/tests/dromaeo/cssquery-prototype.html) 05:41:54 INFO - PROCESS | 1869 | RSS: Main: 243662848 05:41:54 INFO - PROCESS | 1869 | 05:44:57 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53134/tests/dromaeo/cssquery-yui.html) 05:44:58 INFO - PROCESS | 1869 | RSS: Main: 223023104 05:44:58 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:53134/tests/dromaeo/cssquery-yui.html (next: http://localhost:53134/tests/dromaeo/cssquery-dojo.html) 05:48:01 INFO - PROCESS | 1869 | RSS: Main: 259461120 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | __start_tp_report 05:48:01 INFO - PROCESS | 1869 | _x_x_mozilla_page_load 05:48:01 INFO - PROCESS | 1869 | _x_x_mozilla_page_load_details 05:48:01 INFO - PROCESS | 1869 | |i|pagename|runs| 05:48:01 INFO - PROCESS | 1869 | |0;dojo.html;2663.42736311693;2684.5704687250045;2696.123759778073;2716.9320766980827;2726.7273272672737;4558.726476411418;4603.930941035879;4673.182193116204;4757.42910850698;4787.35370724952;4634.559716826904;4684.836030738917;4689.32004859296;4694.225452800284;4740.525947405258;4258;4278.700490965633;4312.698111132222;4371.8688439346715;4477.171723231213;4399.42807435033;4454.777261136946;4480.955190448102;4496.460424749034;4501.099780043988;4232.746035237896;4250.3199488081755;4278.122984788111;4347.456567929009;4368.519462859099;4257.063446041866;4281.293586558214;4283.785810709452;4289.2922667759785;4305.763183024932;4304.741715497049;4315.741055536647;4319.546447622985;4347;4355.259605867011;4490.7754612269255;4556.3621093047;4620.907581848378;4671.229247174212;4675.228587283094;4993.0263598598285;5025;5042.42012168598;5067.6452648314635;5088.363954505687;5049.444561098277;5050.065737838475;5070.2394640803805;5099.133147364957;5110.795568177268;4581.885452863638;4658.7437690926645;4662.0209755950955;4688.179568575486;4707.646926480527;5151.098557752453;5176.042432150064;5217.530422262014;5231.607629427808;5235.554977826851;4950.158473059589;4972;5011.047900898818;5017.724025178651;5022.397312322471;4850.805967761321;4940.184869496565;4991.80032798684;4994.700317980933;5002.624803139706;4956.479569645207;4969.229769385751;4985.501449854985;4989.825356112518;5041.924371134436;4933.45731969483;4955.331030310862;4959.355283813154;4960.950390496121;5003.224500202475;4122.793860306972;4156.501219853637;4162.937555936664;4211.705180637326;4224.260754367973;3708.79601621908;3737.7757334560015;3742.3825068863875;3746.269477451925;3754.2303827715255;4348.782560871944;4365.825366985285;4367.912641747147;4410.669199810027;4412.183746006999;5058.721770302595;5068.873278168076;5069.619778516626;5071.4421413645205;5074.492550744896;4159.854405095807;4321.0709697415205;4357.346398040319;4362.541933097043;4374.387585737999;4438.068005718771;4469.5530446955045;4528.207563676344;4541.613962813131;4577.382053422812;4772.331873537708;4788.784504697227;4791.448983366888;4802.967362017183;4837.4920633334405;4861.343718597944;4931.457539670705;4969.751512424292;4990.301357809837;5068.214426763858;4703.412073490814;4715.528447155394;4721.952780472288;4736.550027747358;4771.904561908673;4854.83008094715;4914.950850491449;4915.26271059344;4919.704817710949;4945.925811112764;4831.347768051409;4951.257311403318;5020.272060551272;5020.49795020495;5034.043531728959;4869.318295438712;4878.975605121952;4886.169351210374;4886.389201349832;4926.901461970812;4759.309900064541;4891.046245981999;4895.681780684244;4898.363212782316;4949.752512374439;4791.161546729239;4815.903681926272;4843.951560484491;4907.2393778964315;4915.680480768739;4956.157453232887;4961.355023847022;4966.18058020437;4977.427595826382;5017.172166592617;4663.533646635309;4863.586595139312;4863.708177509361;4876.122297986255;4921.212605983025 05:48:01 INFO - PROCESS | 1869 | |1;ext.html;557.9477828265058;585.4262822434015;596.2159759915709;604.8094850122209;605.927288725353;28880.277993050186;31713;31734.047978560622;31785.04644860652;31802.36395272096;31370;44220.55779442205;44845.1030979381;45084;49227.50772492274;8021.6390262438035;8135.918640813576;8151.06262779782;8158.469699469545;8159.184081591823;7543.471956963015;7947.880781788278;8029.558374289411;9912.851307230398;10184.439855807928;10087;10125.392476451436;10137.239707021967;10138.59445622178;10143.036411540928;10292.794144117113;10385.169186465064;10387.532561034772;10389.064984151462;10396.428196449193;7528.623568821537;9866.408015519091;10273;10302.33034852732;10614.78770424595;41444;42042;42045.579544204775;42140;42178.36732449016;3344.7658663893535;3356.597208334991;3395.9660403395897;3401.795892246473;3744.7378683492166;30597;31080;31204;31290.37419251602;31306.843465782524;3534.6818786309354;3534.9469757953652;3646.4894914711963;3715.089802998256;3716.145286584074;260353;260544;260566;260596;261874.9999999981;33815.15462113418;34427.827860861034;55385.72307138438;55524.722376388665;56057.71971140118;36298;36349;36476.81761591175;36500.45249321262;36549.45175822365;14256.643583910485;14536.418543258364;14562.199079050753;14562.344694488773;14636.560903172922;10977.066949309397;11776.352300623377;11869.643910682693;11897.405129743478;11902.523899044114;8238.09380968093;8472.703455379033;8488.108748581433;8545.700900468453;8554.315654747605;3250.869965201413;3305.8512366943546;3341.6658334166873;3344.782589131699;3398.8130652814334;921.5530467723149;936.1574582875467;939.6382392778829;1023.6468418395641;1058.7353161709573;29391.26521836971;29612.851935740182;29616.259593509905;30209.395812083636;30265.848670756506;850.3962186847284;853.8633818588996;866.9869951950726;905.0903841639242;1101.5263436722125;15523;16190;16364.83635163657;16828.915855420888;16902.154892255337;398620;400982;403099;404486;404487;54502.72748636232;55109.724451377486;55142.448575514536;55243.447565524635;55445;1194.2118201986648;1278.181963543333;1294.3657607772311;1298.3443361102773;1353.2151352215494;28785.136445906657;30102.548461773527;30203.395932080795;30450.84774576113;30660.540091898194;43249.56750432456;43698.56301436945;43699.56300436955;43874;44088.33867491926;316662;319277;320436;320674;320691;22779.430514237276;23516.529669406857;25326.240212793644;26091.478170436105;26294.474110518066;41614.58385416107;42353.364699530146;42532.5746742541;43391;43459;36760.44859327059;37927.81036094802;38018.61981380151;38057.42913856239;38602;37443.25113497769;38699.2260154801;40832.79583602063;41803;41879.58120418757;37976.43035354527;38986.415203772536;39512;39539;39863.60136398599;33949.49075763915;35066.729393121954;35122.12194695153;35873.82063089668;35925.461118083775;26058;26821.463570728865;27132.72867271302;27183.72816271891;27236.05107566934 05:48:01 INFO - PROCESS | 1869 | |2;jquery.html;381.64507008482116;390.1047096912586;390.4514157608561;402.95366032906225;403.22983102274645;16519.421820236294;17484.82515174848;18389.908050459744;18447.354342598013;18460.81539184606;17512.649747005053;17594.82405175948;18344.449666510027;18451.077446127707;18454.16956236969;5108.923366149511;5224.686518808883;5272.525472707455;5297.735113244342;5302.840914772543;5256.92114618281;5587.692676902769;5595.21666966625;5658.8019419320335;5674.574406919477;5593.8601534961745;5631.8592035199035;5632.746526406321;5636.379998200195;5637.915431268534;6047.455728984391;6050.092486127072;6059.242594675666;6075.119107729399;6105.633661980295;5243.764030618631;5322.121849894763;5390.568754499631;5396.487333703292;5403.486668766461;13109.60671179866;13334.599962001155;18532.258709651596;21216.575668486697;21633.67549486743;1961.3429501117143;1995.6507611167988;2057.609054279682;2110.3879874836434;2332.07882886259;9578;9909.108180263738;10010.399376037461;10894.18293627973;13126.212427254395;1767.6199617082389;1839.705647096458;1864.179760905211;1890.7636545431822;1899.135893168605;63357;63593.36406635921;66571.3342866565;71884;74491;10091.293609447268;10211.897881021243;10538.104261137736;10563.683089507327;10703.25077244601;14357.066790658573;14436.639084022983;14442.855571444361;14445.422183112558;14469.276536173149;7432.368248698921;7672.578008209491;7773;7838.725644602411;7869.331106855867;7290.744823931137;7423.183449820515;7492.026036615205;7506.136794268729;7560.168381478033;4432.800523976429;4443.466783985878;4485;4661.160991021648;4668.5564871337165;1727.9654406912043;1731.6190438103597;1753.4914874686199;1753.7807774028247;1777.5289548269718;942.8255772682076;983.6606370802061;987.8073775613686;1001.4892404873422;1032.2361452525226;17097.48707538776;17357.392491262744;17452.301907923797;17499.56251093733;17760.200790964438;860.5826174305457;908.2370808520874;951.3340661536952;958.8274110660149;968.1625394034251;9553.856692149484;9891.258155638356;9917.553710083064;9964.153046990941;10063.345882517613;418956;426800;427696;434803;435966;78469.60765196252;84452;85908.14091859126;86034.56982715172;86045.56977215073;927.7912469694264;935.8876934767871;949.8242825077383;952.1288021460425;955.9235261179228;15338.386464541294;15566.766498502759;15625.531234062997;15710.528684139494;16080.758788618414;33174.66825331812;33288.83355583207;34511.30977380388;36136;37614;43988;45276;48708.756456217496;50662.2400663983;50763.23855142102;6700.229473610597;6967.1291088613925;7088.893666594902;7172.605506697182;7329.1937886833475;11061.778764424827;12103;12291.75416491683;12297.754044919231;12344.938275308567;10737.194710396907;10800.891991080303;10851.620193293196;10912.508937097691;10926.78146437051;10550.472476375997;10630.946845265724;10743.946280268548;10758.462076895967;10803.72990675208;10754;11062.834057488983;11083.279586826831;11135;11141.554337826721;6872.209695885037;7152.284771522806;7392.778216653509;7394.519356241827;7471.738489152854;10189.184865210616;10753.46232688378;10806.891931080589;10823.080038196842;10885.945570272099 05:48:01 INFO - PROCESS | 1869 | |3;mootools.html;1273.0706584193545;1477.4976507987283;1516.2039929037257;1702.8807983441156;1877.6714075036873;6760.222574403945;7707.383409327254;7946.443748937562;7953.761387158387;8193.057798353191;7920.485168464046;8097.352211823055;8108.2297181767635;8109.91890081099;8110.635021424036;2272.7272727272707;2305.4236440889777;2311.202635090891;2313.560423519534;2313.791758741713;2256.8645881247176;2281.0989659084653;2287.370972982435;2290.770922907704;2291.9885400572975;2290.816734661223;2308.988455057722;2321.3732292281074;2340.918067867625;2342.426105604121;2243.0354947372625;2245.5059886824874;2247.3819699582664;2250.2124256510174;2250.7524172341027;2200.8129309008755;2204.1183526589493;2206.9117235310728;2208.6134926387817;2212.23677831148;7947.72182973599;7966.681332746683;8015.398845086584;8028.438009339348;8040.758777236692;4851.490593487668;4987.301777751081;4989.800407983676;4995.525425084611;4996.675216110977;2959.6942908113174;3019.667836537979;3053.7709671774705;3071.063325685665;3089.845507724605;896.2829736211005;923.1230331185334;938.436937837299;943.0804965158984;943.5235206220815;10846.67459976202;10892;10896.291741036806;10918.563257469692;10945.781084378348;4201.537830838605;4292.248856450171;4359.498657654411;4363.781810909441;4376.956230437719;8657.350698697623;8719.956400217958;8721.956390218009;8731.388802783744;8731.869021964676;4731.550502702238;4752.572268495852;4755.405574303212;4774.618030557547;4781.95218047822;3714.795686237229;4284.186004659105;4384.605385515319;4393.714408563433;4410.316400957857;3602.405603075463;3613.9277214456088;3683.5211422515436;3707.703383729295;3726.9440958385644;1098.3739268616812;1100.9339439633488;1104.4256986367045;1112.4437781109445;1116.1294190531398;680.1328306409367;704.8167476456056;750.6096829648552;815.4210510537584;861.6079683743882;5081.18701007837;5085.5168758968575;5429.158480435539;5470.890582188334;5497.367802702661;857.9485230886166;904.4302089683457;905.1762895764954;940.5532372123187;958.242988039455;3471.9132021699156;3681.2637472505608;3692.593814680383;3711.870084547028;3712;9471.10024547667;9593.712188634212;9901.801963960825;9903.603855845686;9914.752131196778;9763.414195148314;9804.36271642341;9834.557444914995;9844.114029737359;9846.310758246855;7298.197198308182;7382.335589796837;7434.73978410753;7497.212792656802;7507.099148102154;5353.330833645794;5385.488378604026;5387.955577341071;5406.702631355156;5426;9147.451152930846;9181.357304988587;9252.907470925205;9258.95370523143;9266.953665231631;8619.482831030158;8813.383063185516;8926.509042002752;9147;9301.534923253945;7049.29507049291;7209.567425954249;7217.530860494051;7221.205667376689;7235.312645298688;7716.305532502101;7780;7784.844303113793;7790.143084260613;7791.220877912164;5544.41783612731;6817.42051925572;6817.795466136024;6845.794626161223;7000.50996430245;6025.517958563392;6624.834379140368;6689.2307384651385;6805.217399998865;6893.724251029903;6495.967520162369;6511.47908167336;6547.639879806466;6551.44312733423;6587.9670601646685;4300.526942036436;4513.819447222074;4581.816727331003;4599.931001035054;4714.905701885875;4631.791569379318;4677.649176311722;4775.283707443911;4784.186688262934;4804.927926081182 05:48:01 INFO - PROCESS | 1869 | |4;prototype.html;317.50152260950307;320.9823459709715;324.4387210126481;327.8033180091945;330.98345082745857;4810.08608364411;4850.078485087835;4855.368802055732;4857.635677324197;4894.650567727493;4783.402074740658;4799.7360145192;4815.0610630926985;4817.831375901844;4850.781714822832;3664.4320130379765;3750.9624903750887;3776.527934008249;3879.495665563487;3881.5342158940825;3811.5235595550557;3858.59484754101;3866.4534186325554;3872.051347419886;3875.360565506702;3816.5992570780086;3819.2743378758087;3830.693544516432;3839.827207775643;3880.611938806125;3664.175560498893;3692.796896170723;3794.6584807367194;3841.154945911895;3847.7306588538813;3384.526166336715;3394.62659107498;3423.298223864102;3427.4687423449404;3431.759776815624;4627.213373726475;4667.22990706533;4674.368960190399;4683.789229484647;4699.412573428322;4280.785960701984;4378.190034843564;4388.517263101056;4391.934120988188;4394.736315821061;5038.470960549126;5051.848444546669;5058.266551350071;5070.594352451832;5073.797048118071;3730;3730.7388482806214;3732.7387082904206;3749.1751814600743;3766.7551609145316;5076.568491678212;5282.31329927107;5320.361556613192;5336.733163341817;5346.705931173784;3824.196918647115;3831.904202394907;3850.5764365919727;3862.6909847212155;3867.3038853006165;3895.7078219133678;3920.5491368492417;3924.4701965234326;3927.430522574211;3932.803359832054;4352.499462561783;4362.716423432466;4365.388845561624;4381.693281470267;4396.934045989313;4834.806607735652;5088.949110508847;5094;5109.948900510947;5122.9743851281255;1291.6125162451415;1304.3673818198356;1304.7716649586284;1310.2924420812653;1314.2574445438297;807.463037080335;814.9511029338258;846.1284876577013;852.2500199824115;855.5679381912095;4415.801288942005;4455.6212721918355;4497.685162038626;4507.061963355723;4533.52397998212;5243.790248390098;5264.499872512182;5277.076511610453;5281;5282.6302158849285;4514.413126293561;4517.186906356822;4520.141173177086;4532;4545.477270113913;2651.5890037044287;2799.678037025768;2813.184176588807;2817.788036391423;2837;4883.438404583519;4934.333864928189;4941.925871111936;4989.750512474362;4999.875003124951;5241.554467870197;5381.412483317431;5383.300170977748;5391;5408.512659018801;4972.129877271463;4976.576990955737;4993.275974983607;5005.399352077774;5015.5736762374145;5308.601854860978;5321.3348331458565;5331.70675612845;5336.557920141565;5344.919826202532;4995.200767877122;5208.765605547684;5208.8437346879655;5217;5225.738713064407;4925.817803727008;4959.776810043484;5021.5229553192385;5028.170351892043;5045.596352291735;3406.84669189892;3433.8798142064907;3471.8821518873974;3482.5820901491984;3499.580050393969;4566.954330456653;4737.644676649196;4769.57073863354;4787.880302992453;4798.760061996816;4591.760224739235;4599.67802253839;4602.907941841211;4603.457841623093;4621.682820396171;4559.065391594649;4597.7011494252765;4613.607843333354;4620.745858977789;4621.884452888705;4422;4532.478764942074;4555.658325625657;4565;4627.9768601156775;4651.348811166372;4932.926006109983;5058.822941197041;5095.668781529189;5104;4927.28554359623;4986.202207646759;4990.850274491771;5050.696958182521;5077.466865979019 05:48:01 INFO - PROCESS | 1869 | |5;yui.html;650.3528988656286;779.5244900610627;780.1769133564088;785.579714852554;794.6185830801215;10466.215033872451;12163.148579599432;12347.691307717312;12368;12412.06909481789;12189.207701499396;12336.81494777579;12343.814842777365;12408.627741167757;12504.437300321484;680.8638272345526;832.9583520823965;865.6753717356007;872.6989188729909;877.8156587116713;869.5304535550795;878.6748902905933;879.1911441473827;879.4283715584857;882.5190271302124;914.1635403605693;917.2845180759018;917.3716004536881;919.797644518205;920.397139873384;704.436450839329;886.0829041941624;906.7687739626354;908.0556221529602;909.4316052467208;679.5752654590881;680.0717021265986;680.8331958670108;681.0567364200618;681.6216999565289;13145.86854131456;13170.078094533386;13192.076554641179;13196.076274660778;13201.867981320256;11960.64118076459;12465.501379944792;12660.502825648491;12729.618111456579;12768.553100641433;9989.700308990741;10900.618478353299;11040.447977601169;11151.163662725246;11169.609063682814;4123.195976784528;4176.373543968399;4221.91556168878;4240.957590424087;4298.419713338691;50233;50259;50327;50648.49351506474;50820.23769643495;10113.898861011443;10227.181825453947;10282.228832837567;10283.640072597424;10396.48017599132;17918.820811791975;18127.72808407875;18139.90930045341;18142.63714725698;18450.815491845176;6361.332060133619;6480.384363485461;6488.383603557654;6495.285518592951;6521.217453905561;3837.175007373428;3854.5759966403675;3908.863189788344;3937.7637341759587;3951.723379363417;2794.413173233603;2853.7288957549;2854.6288982432566;2855.029290041354;2866.226118947914;502.311832789074;510.89015861589934;511.1770050219144;547.479894100606;577.7313549199642;483.72185993054137;624.4130517313711;639.4468784501465;639.7664852328867;647.1102234427661;8202.227679789683;8567.914320856837;8573.22840944318;8649.956750216334;8705;534.2067030459764;580.1703563903658;600.6336134957672;611.4710775179437;613.6318209074609;3957.485526881487;4087;4120.62914337711;4146;4193.874183774492;48279;54739.17891231635;55539.72230138823;55667;55830.44169558252;50486.495135048914;50770.74614626903;51083.7445812776;51455;52140.739296303276;47331;47812.28281575779;48491;48546.75726621344;48845.99999999929;6911.3779759821855;6918.688659010458;7007.859842803217;7124.715011399486;7189.460790440842;30443.391132177676;30741.8462907693;30875.9999999991;32835.67164328326;33009;42007.78996105;42344.364834528125;42414.78792606017;42507;44107;11131.387773672526;11135.610253641084;11146.88853111491;11514.481848316678;11619.244749091282;12396.690082747642;12465.5013799447;12556.87443125557;12984.091113622333;13177.209367437985;11846.585369512026;11862.703432414259;12060.396980151132;12430.937845311077;12595;11559;11796.528138874692;12070.879291206975;12144.696382590506;12172.2696638202;11630.88369116298;11663.650090497298;11681.474333654836;12242;12265.362946966445;9488.762780930532;9528.904710952802;9678.612855485702;9788.706338809847;9907.405555666683;9615.51922403891;9664.9033509664;9692.75768105803;9811.215102791617;9838.557264923238 05:48:01 INFO - PROCESS | 1869 | __end_tp_report 05:48:01 INFO - PROCESS | 1869 | __start_cc_report 05:48:01 INFO - PROCESS | 1869 | _x_x_mozilla_cycle_collect,975 05:48:01 INFO - PROCESS | 1869 | __end_cc_report 05:48:01 INFO - PROCESS | 1869 | __startTimestamp1463489281483__endTimestamp 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | ------- Summary: start ------- 05:48:01 INFO - PROCESS | 1869 | Number of tests: 6 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#0] dojo.html Cycles:165 Average:4644.30 Median:4787.35 stddev:477.43 (10.0%) stddev-sans-first:452.90 05:48:01 INFO - PROCESS | 1869 | Values: 2663.4 2684.6 2696.1 2716.9 2726.7 4558.7 4603.9 4673.2 4757.4 4787.4 4634.6 4684.8 4689.3 4694.2 4740.5 4258.0 4278.7 4312.7 4371.9 4477.2 4399.4 4454.8 4481.0 4496.5 4501.1 4232.7 4250.3 4278.1 4347.5 4368.5 4257.1 4281.3 4283.8 4289.3 4305.8 4304.7 4315.7 4319.5 4347.0 4355.3 4490.8 4556.4 4620.9 4671.2 4675.2 4993.0 5025.0 5042.4 5067.6 5088.4 5049.4 5050.1 5070.2 5099.1 5110.8 4581.9 4658.7 4662.0 4688.2 4707.6 5151.1 5176.0 5217.5 5231.6 5235.6 4950.2 4972.0 5011.0 5017.7 5022.4 4850.8 4940.2 4991.8 4994.7 5002.6 4956.5 4969.2 4985.5 4989.8 5041.9 4933.5 4955.3 4959.4 4961.0 5003.2 4122.8 4156.5 4162.9 4211.7 4224.3 3708.8 3737.8 3742.4 3746.3 3754.2 4348.8 4365.8 4367.9 4410.7 4412.2 5058.7 5068.9 5069.6 5071.4 5074.5 4159.9 4321.1 4357.3 4362.5 4374.4 4438.1 4469.6 4528.2 4541.6 4577.4 4772.3 4788.8 4791.4 4803.0 4837.5 4861.3 4931.5 4969.8 4990.3 5068.2 4703.4 4715.5 4722.0 4736.6 4771.9 4854.8 4915.0 4915.3 4919.7 4945.9 4831.3 4951.3 5020.3 5020.5 5034.0 4869.3 4879.0 4886.2 4886.4 4926.9 4759.3 4891.0 4895.7 4898.4 4949.8 4791.2 4815.9 4844.0 4907.2 4915.7 4956.2 4961.4 4966.2 4977.4 5017.2 4663.5 4863.6 4863.7 4876.1 4921.2 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#1] ext.html Cycles:180 Average:48083.11 Median:35094.43 stddev:87629.21 (249.7%) stddev-sans-first:87802.38 05:48:01 INFO - PROCESS | 1869 | Values: 557.9 585.4 596.2 604.8 605.9 28880.3 31713.0 31734.0 31785.0 31802.4 31370.0 44220.6 44845.1 45084.0 49227.5 8021.6 8135.9 8151.1 8158.5 8159.2 7543.5 7947.9 8029.6 9912.9 10184.4 10087.0 10125.4 10137.2 10138.6 10143.0 10292.8 10385.2 10387.5 10389.1 10396.4 7528.6 9866.4 10273.0 10302.3 10614.8 41444.0 42042.0 42045.6 42140.0 42178.4 3344.8 3356.6 3396.0 3401.8 3744.7 30597.0 31080.0 31204.0 31290.4 31306.8 3534.7 3534.9 3646.5 3715.1 3716.1 260353.0 260544.0 260566.0 260596.0 261875.0 33815.2 34427.8 55385.7 55524.7 56057.7 36298.0 36349.0 36476.8 36500.5 36549.5 14256.6 14536.4 14562.2 14562.3 14636.6 10977.1 11776.4 11869.6 11897.4 11902.5 8238.1 8472.7 8488.1 8545.7 8554.3 3250.9 3305.9 3341.7 3344.8 3398.8 921.6 936.2 939.6 1023.6 1058.7 29391.3 29612.9 29616.3 30209.4 30265.8 850.4 853.9 867.0 905.1 1101.5 15523.0 16190.0 16364.8 16828.9 16902.2 398620.0 400982.0 403099.0 404486.0 404487.0 54502.7 55109.7 55142.4 55243.4 55445.0 1194.2 1278.2 1294.4 1298.3 1353.2 28785.1 30102.5 30203.4 30450.8 30660.5 43249.6 43698.6 43699.6 43874.0 44088.3 316662.0 319277.0 320436.0 320674.0 320691.0 22779.4 23516.5 25326.2 26091.5 26294.5 41614.6 42353.4 42532.6 43391.0 43459.0 36760.4 37927.8 38018.6 38057.4 38602.0 37443.3 38699.2 40832.8 41803.0 41879.6 37976.4 38986.4 39512.0 39539.0 39863.6 33949.5 35066.7 35122.1 35873.8 35925.5 26058.0 26821.5 27132.7 27183.7 27236.1 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#2] jquery.html Cycles:180 Average:25609.94 Median:209679.61 stddev:70644.23 (33.7%) stddev-sans-first:70817.01 05:48:01 INFO - PROCESS | 1869 | Values: 381.6 390.1 390.5 403.0 403.2 16519.4 17484.8 18389.9 18447.4 18460.8 17512.6 17594.8 18344.4 18451.1 18454.2 5108.9 5224.7 5272.5 5297.7 5302.8 5256.9 5587.7 5595.2 5658.8 5674.6 5593.9 5631.9 5632.7 5636.4 5637.9 6047.5 6050.1 6059.2 6075.1 6105.6 5243.8 5322.1 5390.6 5396.5 5403.5 13109.6 13334.6 18532.3 21216.6 21633.7 1961.3 1995.7 2057.6 2110.4 2332.1 9578.0 9909.1 10010.4 10894.2 13126.2 1767.6 1839.7 1864.2 1890.8 1899.1 63357.0 63593.4 66571.3 71884.0 74491.0 10091.3 10211.9 10538.1 10563.7 10703.3 14357.1 14436.6 14442.9 14445.4 14469.3 7432.4 7672.6 7773.0 7838.7 7869.3 7290.7 7423.2 7492.0 7506.1 7560.2 4432.8 4443.5 4485.0 4661.2 4668.6 1728.0 1731.6 1753.5 1753.8 1777.5 942.8 983.7 987.8 1001.5 1032.2 17097.5 17357.4 17452.3 17499.6 17760.2 860.6 908.2 951.3 958.8 968.2 9553.9 9891.3 9917.6 9964.2 10063.3 418956.0 426800.0 427696.0 434803.0 435966.0 78469.6 84452.0 85908.1 86034.6 86045.6 927.8 935.9 949.8 952.1 955.9 15338.4 15566.8 15625.5 15710.5 16080.8 33174.7 33288.8 34511.3 36136.0 37614.0 43988.0 45276.0 48708.8 50662.2 50763.2 6700.2 6967.1 7088.9 7172.6 7329.2 11061.8 12103.0 12291.8 12297.8 12344.9 10737.2 10800.9 10851.6 10912.5 10926.8 10550.5 10630.9 10743.9 10758.5 10803.7 10754.0 11062.8 11083.3 11135.0 11141.6 6872.2 7152.3 7392.8 7394.5 7471.7 10189.2 10753.5 10806.9 10823.1 10885.9 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#3] mootools.html Cycles:180 Average:5237.46 Median:5416.35 stddev:2909.10 (53.7%) stddev-sans-first:2902.00 05:48:01 INFO - PROCESS | 1869 | Values: 1273.1 1477.5 1516.2 1702.9 1877.7 6760.2 7707.4 7946.4 7953.8 8193.1 7920.5 8097.4 8108.2 8109.9 8110.6 2272.7 2305.4 2311.2 2313.6 2313.8 2256.9 2281.1 2287.4 2290.8 2292.0 2290.8 2309.0 2321.4 2340.9 2342.4 2243.0 2245.5 2247.4 2250.2 2250.8 2200.8 2204.1 2206.9 2208.6 2212.2 7947.7 7966.7 8015.4 8028.4 8040.8 4851.5 4987.3 4989.8 4995.5 4996.7 2959.7 3019.7 3053.8 3071.1 3089.8 896.3 923.1 938.4 943.1 943.5 10846.7 10892.0 10896.3 10918.6 10945.8 4201.5 4292.2 4359.5 4363.8 4377.0 8657.4 8720.0 8722.0 8731.4 8731.9 4731.6 4752.6 4755.4 4774.6 4782.0 3714.8 4284.2 4384.6 4393.7 4410.3 3602.4 3613.9 3683.5 3707.7 3726.9 1098.4 1100.9 1104.4 1112.4 1116.1 680.1 704.8 750.6 815.4 861.6 5081.2 5085.5 5429.2 5470.9 5497.4 857.9 904.4 905.2 940.6 958.2 3471.9 3681.3 3692.6 3711.9 3712.0 9471.1 9593.7 9901.8 9903.6 9914.8 9763.4 9804.4 9834.6 9844.1 9846.3 7298.2 7382.3 7434.7 7497.2 7507.1 5353.3 5385.5 5388.0 5406.7 5426.0 9147.5 9181.4 9252.9 9259.0 9267.0 8619.5 8813.4 8926.5 9147.0 9301.5 7049.3 7209.6 7217.5 7221.2 7235.3 7716.3 7780.0 7784.8 7790.1 7791.2 5544.4 6817.4 6817.8 6845.8 7000.5 6025.5 6624.8 6689.2 6805.2 6893.7 6496.0 6511.5 6547.6 6551.4 6588.0 4300.5 4513.8 4581.8 4599.9 4714.9 4631.8 4677.6 4775.3 4784.2 4804.9 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#4] prototype.html Cycles:180 Average:4190.86 Median:4608.53 stddev:1198.19 (26.0%) stddev-sans-first:1165.75 05:48:01 INFO - PROCESS | 1869 | Values: 317.5 321.0 324.4 327.8 331.0 4810.1 4850.1 4855.4 4857.6 4894.7 4783.4 4799.7 4815.1 4817.8 4850.8 3664.4 3751.0 3776.5 3879.5 3881.5 3811.5 3858.6 3866.5 3872.1 3875.4 3816.6 3819.3 3830.7 3839.8 3880.6 3664.2 3692.8 3794.7 3841.2 3847.7 3384.5 3394.6 3423.3 3427.5 3431.8 4627.2 4667.2 4674.4 4683.8 4699.4 4280.8 4378.2 4388.5 4391.9 4394.7 5038.5 5051.8 5058.3 5070.6 5073.8 3730.0 3730.7 3732.7 3749.2 3766.8 5076.6 5282.3 5320.4 5336.7 5346.7 3824.2 3831.9 3850.6 3862.7 3867.3 3895.7 3920.5 3924.5 3927.4 3932.8 4352.5 4362.7 4365.4 4381.7 4396.9 4834.8 5088.9 5094.0 5109.9 5123.0 1291.6 1304.4 1304.8 1310.3 1314.3 807.5 815.0 846.1 852.3 855.6 4415.8 4455.6 4497.7 4507.1 4533.5 5243.8 5264.5 5277.1 5281.0 5282.6 4514.4 4517.2 4520.1 4532.0 4545.5 2651.6 2799.7 2813.2 2817.8 2837.0 4883.4 4934.3 4941.9 4989.8 4999.9 5241.6 5381.4 5383.3 5391.0 5408.5 4972.1 4976.6 4993.3 5005.4 5015.6 5308.6 5321.3 5331.7 5336.6 5344.9 4995.2 5208.8 5208.8 5217.0 5225.7 4925.8 4959.8 5021.5 5028.2 5045.6 3406.8 3433.9 3471.9 3482.6 3499.6 4567.0 4737.6 4769.6 4787.9 4798.8 4591.8 4599.7 4602.9 4603.5 4621.7 4559.1 4597.7 4613.6 4620.7 4621.9 4422.0 4532.5 4555.7 4565.0 4628.0 4651.3 4932.9 5058.8 5095.7 5104.0 4927.3 4986.2 4990.9 5050.7 5077.5 05:48:01 INFO - PROCESS | 1869 | 05:48:01 INFO - PROCESS | 1869 | [#5] yui.html Cycles:180 Average:13626.90 Median:48045.64 stddev:15739.37 (32.8%) stddev-sans-first:15753.35 05:48:01 INFO - PROCESS | 1869 | Values: 650.4 779.5 780.2 785.6 794.6 10466.2 12163.1 12347.7 12368.0 12412.1 12189.2 12336.8 12343.8 12408.6 12504.4 680.9 833.0 865.7 872.7 877.8 869.5 878.7 879.2 879.4 882.5 914.2 917.3 917.4 919.8 920.4 704.4 886.1 906.8 908.1 909.4 679.6 680.1 680.8 681.1 681.6 13145.9 13170.1 13192.1 13196.1 13201.9 11960.6 12465.5 12660.5 12729.6 12768.6 9989.7 10900.6 11040.4 11151.2 11169.6 4123.2 4176.4 4221.9 4241.0 4298.4 50233.0 50259.0 50327.0 50648.5 50820.2 10113.9 10227.2 10282.2 10283.6 10396.5 17918.8 18127.7 18139.9 18142.6 18450.8 6361.3 6480.4 6488.4 6495.3 6521.2 3837.2 3854.6 3908.9 3937.8 3951.7 2794.4 2853.7 2854.6 2855.0 2866.2 502.3 510.9 511.2 547.5 577.7 483.7 624.4 639.4 639.8 647.1 8202.2 8567.9 8573.2 8650.0 8705.0 534.2 580.2 600.6 611.5 613.6 3957.5 4087.0 4120.6 4146.0 4193.9 48279.0 54739.2 55539.7 55667.0 55830.4 50486.5 50770.7 51083.7 51455.0 52140.7 47331.0 47812.3 48491.0 48546.8 48846.0 6911.4 6918.7 7007.9 7124.7 7189.5 30443.4 30741.8 30876.0 32835.7 33009.0 42007.8 42344.4 42414.8 42507.0 44107.0 11131.4 11135.6 11146.9 11514.5 11619.2 12396.7 12465.5 12556.9 12984.1 13177.2 11846.6 11862.7 12060.4 12430.9 12595.0 11559.0 11796.5 12070.9 12144.7 12172.3 11630.9 11663.7 11681.5 12242.0 12265.4 9488.8 9528.9 9678.6 9788.7 9907.4 9615.5 9664.9 9692.8 9811.2 9838.6 05:48:01 INFO - PROCESS | 1869 | -------- Summary: end -------- 05:48:01 INFO - PROCESS | 1869 | 05:48:02 INFO - TEST-INFO | 1869: exit 0 05:48:02 INFO - TEST-OK | dromaeo_css | took 1119255ms 05:48:02 INFO - TEST-START | kraken 05:48:02 INFO - operating with platform_type : linux_ 05:48:02 INFO - Initialising browser for kraken test... 05:48:02 INFO - TEST-INFO | started process 2044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZxq2PN/profile http://localhost:53134/getInfo.html) 05:48:06 INFO - PROCESS | 2044 | __metrics Screen width/height:1600/1200 05:48:06 INFO - PROCESS | 2044 | colorDepth:24 05:48:06 INFO - PROCESS | 2044 | Browser inner width/height: 1024/697 05:48:06 INFO - PROCESS | 2044 | __metrics 05:48:07 ERROR - PROCESS | 2044 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:48:08 INFO - TEST-INFO | 2044: exit 0 05:48:08 INFO - Browser initialized. 05:48:08 INFO - Running cycle 1/1 for kraken test... 05:48:08 INFO - TEST-INFO | started process 2126 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZxq2PN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:20 INFO - PROCESS | 2126 | RSS: Main: 154963968 05:48:20 INFO - PROCESS | 2126 | 05:48:22 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53134/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:23 INFO - PROCESS | 2126 | RSS: Main: 210112512 05:48:23 INFO - PROCESS | 2126 | 05:48:26 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53134/tests/kraken/driver.html?testName=audio-dft) 05:48:27 INFO - PROCESS | 2126 | RSS: Main: 197320704 05:48:27 INFO - PROCESS | 2126 | 05:48:30 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53134/tests/kraken/driver.html?testName=audio-fft) 05:48:31 INFO - PROCESS | 2126 | RSS: Main: 266924032 05:48:31 INFO - PROCESS | 2126 | 05:48:33 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53134/tests/kraken/driver.html?testName=audio-oscillator) 05:48:34 INFO - PROCESS | 2126 | RSS: Main: 176050176 05:48:34 INFO - PROCESS | 2126 | 05:48:36 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53134/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:37 INFO - PROCESS | 2126 | RSS: Main: 181207040 05:48:37 INFO - PROCESS | 2126 | 05:48:40 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53134/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:41 INFO - PROCESS | 2126 | RSS: Main: 216842240 05:48:41 INFO - PROCESS | 2126 | 05:48:44 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53134/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:45 INFO - PROCESS | 2126 | RSS: Main: 215068672 05:48:45 INFO - PROCESS | 2126 | 05:48:48 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53134/tests/kraken/driver.html?testName=json-parse-financial) 05:48:49 INFO - PROCESS | 2126 | RSS: Main: 215633920 05:48:49 INFO - PROCESS | 2126 | 05:48:50 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53134/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:51 INFO - PROCESS | 2126 | RSS: Main: 209891328 05:48:51 INFO - PROCESS | 2126 | 05:48:53 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:54 INFO - PROCESS | 2126 | RSS: Main: 212643840 05:48:54 INFO - PROCESS | 2126 | 05:48:56 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:56 INFO - PROCESS | 2126 | RSS: Main: 216551424 05:48:56 INFO - PROCESS | 2126 | 05:48:59 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:00 INFO - PROCESS | 2126 | RSS: Main: 220385280 05:49:00 INFO - PROCESS | 2126 | 05:49:03 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:04 INFO - PROCESS | 2126 | RSS: Main: 220000256 05:49:04 INFO - PROCESS | 2126 | 05:49:05 INFO - PROCESS | 2126 | Cycle 1(1): loaded http://localhost:53134/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53134/tests/kraken/driver.html?testName=ai-astar) 05:49:06 INFO - PROCESS | 2126 | RSS: Main: 265814016 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | __start_tp_report 05:49:06 INFO - PROCESS | 2126 | _x_x_mozilla_page_load 05:49:06 INFO - PROCESS | 2126 | _x_x_mozilla_page_load_details 05:49:06 INFO - PROCESS | 2126 | |i|pagename|runs| 05:49:06 INFO - PROCESS | 2126 | |0;ai-astar;113;110;114;115;113;111;112;113;114;113 05:49:06 INFO - PROCESS | 2126 | |1;audio-beat-detection;161;129;154;217;135;155;152;131;198;130 05:49:06 INFO - PROCESS | 2126 | |2;audio-dft;162;164;162;161;162;162;161;161;161;163 05:49:06 INFO - PROCESS | 2126 | |3;audio-fft;77;76;77;157;76;75;77;75;130;75 05:49:06 INFO - PROCESS | 2126 | |4;audio-oscillator;92;92;151;93;92;94;124;93;93;139 05:49:06 INFO - PROCESS | 2126 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 05:49:06 INFO - PROCESS | 2126 | |6;imaging-darkroom;145;146;146;145;145;146;146;146;146;146 05:49:06 INFO - PROCESS | 2126 | |7;imaging-desaturate;94;94;94;95;96;95;94;95;95;94 05:49:06 INFO - PROCESS | 2126 | |8;json-parse-financial;65;64;66;63;66;65;65;67;63;63 05:49:06 INFO - PROCESS | 2126 | |9;json-stringify-tinderbox;64;65;64;64;64;64;64;63;63;64 05:49:06 INFO - PROCESS | 2126 | |10;stanford-crypto-aes;87;85;82;83;84;82;84;84;142;89 05:49:06 INFO - PROCESS | 2126 | |11;stanford-crypto-ccm;131;142;137;132;146;129;129;130;152;130 05:49:06 INFO - PROCESS | 2126 | |12;stanford-crypto-pbkdf2;172;175;170;180;170;176;173;172;174;174 05:49:06 INFO - PROCESS | 2126 | |13;stanford-crypto-sha256-iterative;68;68;68;67;70;67;67;66;65;66 05:49:06 INFO - PROCESS | 2126 | __end_tp_report 05:49:06 INFO - PROCESS | 2126 | __start_cc_report 05:49:06 INFO - PROCESS | 2126 | _x_x_mozilla_cycle_collect,2092 05:49:06 INFO - PROCESS | 2126 | __end_cc_report 05:49:06 INFO - PROCESS | 2126 | __startTimestamp1463489346154__endTimestamp 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | ------- Summary: start ------- 05:49:06 INFO - PROCESS | 2126 | Number of tests: 14 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.48 (1.3%) stddev-sans-first:1.56 05:49:06 INFO - PROCESS | 2126 | Values: 113.0 110.0 114.0 115.0 113.0 111.0 112.0 113.0 114.0 113.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#1] audio-beat-detection Cycles:10 Average:156.20 Median:154.50 stddev:29.82 (19.3%) stddev-sans-first:31.58 05:49:06 INFO - PROCESS | 2126 | Values: 161.0 129.0 154.0 217.0 135.0 155.0 152.0 131.0 198.0 130.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 05:49:06 INFO - PROCESS | 2126 | Values: 162.0 164.0 162.0 161.0 162.0 162.0 161.0 161.0 161.0 163.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#3] audio-fft Cycles:10 Average:89.50 Median:76.00 stddev:29.17 (38.4%) stddev-sans-first:30.59 05:49:06 INFO - PROCESS | 2126 | Values: 77.0 76.0 77.0 157.0 76.0 75.0 77.0 75.0 130.0 75.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#4] audio-oscillator Cycles:10 Average:106.30 Median:92.50 stddev:22.79 (24.6%) stddev-sans-first:23.58 05:49:06 INFO - PROCESS | 2126 | Values: 92.0 92.0 151.0 93.0 92.0 94.0 124.0 93.0 93.0 139.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:49:06 INFO - PROCESS | 2126 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 05:49:06 INFO - PROCESS | 2126 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 05:49:06 INFO - PROCESS | 2126 | Values: 94.0 94.0 94.0 95.0 96.0 95.0 94.0 95.0 95.0 94.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:1.42 (2.2%) stddev-sans-first:1.50 05:49:06 INFO - PROCESS | 2126 | Values: 65.0 64.0 66.0 63.0 66.0 65.0 65.0 67.0 63.0 63.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:49:06 INFO - PROCESS | 2126 | Values: 64.0 65.0 64.0 64.0 64.0 64.0 64.0 63.0 63.0 64.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:84.00 stddev:18.33 (21.8%) stddev-sans-first:19.40 05:49:06 INFO - PROCESS | 2126 | Values: 87.0 85.0 82.0 83.0 84.0 82.0 84.0 84.0 142.0 89.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#11] stanford-crypto-ccm Cycles:10 Average:135.80 Median:134.50 stddev:8.19 (6.1%) stddev-sans-first:8.50 05:49:06 INFO - PROCESS | 2126 | Values: 131.0 142.0 137.0 132.0 146.0 129.0 129.0 130.0 152.0 130.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.00 stddev:2.99 (1.7%) stddev-sans-first:3.11 05:49:06 INFO - PROCESS | 2126 | Values: 172.0 175.0 170.0 180.0 170.0 176.0 173.0 172.0 174.0 174.0 05:49:06 INFO - PROCESS | 2126 | 05:49:06 INFO - PROCESS | 2126 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.40 (2.1%) stddev-sans-first:1.45 05:49:06 INFO - PROCESS | 2126 | Values: 68.0 68.0 68.0 67.0 70.0 67.0 67.0 66.0 65.0 66.0 05:49:06 INFO - PROCESS | 2126 | -------- Summary: end -------- 05:49:06 INFO - PROCESS | 2126 | 05:49:07 INFO - TEST-INFO | 2126: exit 0 05:49:07 INFO - TEST-OK | kraken | took 64963ms 05:49:07 INFO - SUITE-END | took 1184s 05:49:07 INFO - Completed test suite (00:19:44) 05:49:07 INFO - javascript benchmark 05:49:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2663.42736311693, 2684.5704687250045, 2696.123759778073, 2716.9320766980827, 2726.7273272672737, 4558.726476411418, 4603.930941035879, 4673.182193116204, 4757.42910850698, 4787.35370724952, 4634.559716826904, 4684.836030738917, 4689.32004859296, 4694.225452800284, 4740.525947405258, 4258.0, 4278.700490965633, 4312.698111132222, 4371.8688439346715, 4477.171723231213, 4399.42807435033, 4454.777261136946, 4480.955190448102, 4496.460424749034, 4501.099780043988, 4232.746035237896, 4250.3199488081755, 4278.122984788111, 4347.456567929009, 4368.519462859099, 4257.063446041866, 4281.293586558214, 4283.785810709452, 4289.2922667759785, 4305.763183024932, 4304.741715497049, 4315.741055536647, 4319.546447622985, 4347.0, 4355.259605867011, 4490.7754612269255, 4556.3621093047, 4620.907581848378, 4671.229247174212, 4675.228587283094, 4993.0263598598285, 5025.0, 5042.42012168598, 5067.6452648314635, 5088.363954505687, 5049.444561098277, 5050.065737838475, 5070.2394640803805, 5099.133147364957, 5110.795568177268, 4581.885452863638, 4658.7437690926645, 4662.0209755950955, 4688.179568575486, 4707.646926480527, 5151.098557752453, 5176.042432150064, 5217.530422262014, 5231.607629427808, 5235.554977826851, 4950.158473059589, 4972.0, 5011.047900898818, 5017.724025178651, 5022.397312322471, 4850.805967761321, 4940.184869496565, 4991.80032798684, 4994.700317980933, 5002.624803139706, 4956.479569645207, 4969.229769385751, 4985.501449854985, 4989.825356112518, 5041.924371134436, 4933.45731969483, 4955.331030310862, 4959.355283813154, 4960.950390496121, 5003.224500202475, 4122.793860306972, 4156.501219853637, 4162.937555936664, 4211.705180637326, 4224.260754367973, 3708.79601621908, 3737.7757334560015, 3742.3825068863875, 3746.269477451925, 3754.2303827715255, 4348.782560871944, 4365.825366985285, 4367.912641747147, 4410.669199810027, 4412.183746006999, 5058.721770302595, 5068.873278168076, 5069.619778516626, 5071.4421413645205, 5074.492550744896, 4159.854405095807, 4321.0709697415205, 4357.346398040319, 4362.541933097043, 4374.387585737999, 4438.068005718771, 4469.5530446955045, 4528.207563676344, 4541.613962813131, 4577.382053422812, 4772.331873537708, 4788.784504697227, 4791.448983366888, 4802.967362017183, 4837.4920633334405, 4861.343718597944, 4931.457539670705, 4969.751512424292, 4990.301357809837, 5068.214426763858, 4703.412073490814, 4715.528447155394, 4721.952780472288, 4736.550027747358, 4771.904561908673, 4854.83008094715, 4914.950850491449, 4915.26271059344, 4919.704817710949, 4945.925811112764, 4831.347768051409, 4951.257311403318, 5020.272060551272, 5020.49795020495, 5034.043531728959, 4869.318295438712, 4878.975605121952, 4886.169351210374, 4886.389201349832, 4926.901461970812, 4759.309900064541, 4891.046245981999, 4895.681780684244, 4898.363212782316, 4949.752512374439, 4791.161546729239, 4815.903681926272, 4843.951560484491, 4907.2393778964315, 4915.680480768739, 4956.157453232887, 4961.355023847022, 4966.18058020437, 4977.427595826382, 5017.172166592617, 4663.533646635309, 4863.586595139312, 4863.708177509361, 4876.122297986255, 4921.212605983025], "value": 4614.894649692174, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.9477828265058, 585.4262822434015, 596.2159759915709, 604.8094850122209, 605.927288725353, 28880.277993050186, 31713.0, 31734.047978560622, 31785.04644860652, 31802.36395272096, 31370.0, 44220.55779442205, 44845.1030979381, 45084.0, 49227.50772492274, 8021.6390262438035, 8135.918640813576, 8151.06262779782, 8158.469699469545, 8159.184081591823, 7543.471956963015, 7947.880781788278, 8029.558374289411, 9912.851307230398, 10184.439855807928, 10087.0, 10125.392476451436, 10137.239707021967, 10138.59445622178, 10143.036411540928, 10292.794144117113, 10385.169186465064, 10387.532561034772, 10389.064984151462, 10396.428196449193, 7528.623568821537, 9866.408015519091, 10273.0, 10302.33034852732, 10614.78770424595, 41444.0, 42042.0, 42045.579544204775, 42140.0, 42178.36732449016, 3344.7658663893535, 3356.597208334991, 3395.9660403395897, 3401.795892246473, 3744.7378683492166, 30597.0, 31080.0, 31204.0, 31290.37419251602, 31306.843465782524, 3534.6818786309354, 3534.9469757953652, 3646.4894914711963, 3715.089802998256, 3716.145286584074, 260353.0, 260544.0, 260566.0, 260596.0, 261874.9999999981, 33815.15462113418, 34427.827860861034, 55385.72307138438, 55524.722376388665, 56057.71971140118, 36298.0, 36349.0, 36476.81761591175, 36500.45249321262, 36549.45175822365, 14256.643583910485, 14536.418543258364, 14562.199079050753, 14562.344694488773, 14636.560903172922, 10977.066949309397, 11776.352300623377, 11869.643910682693, 11897.405129743478, 11902.523899044114, 8238.09380968093, 8472.703455379033, 8488.108748581433, 8545.700900468453, 8554.315654747605, 3250.869965201413, 3305.8512366943546, 3341.6658334166873, 3344.782589131699, 3398.8130652814334, 921.5530467723149, 936.1574582875467, 939.6382392778829, 1023.6468418395641, 1058.7353161709573, 29391.26521836971, 29612.851935740182, 29616.259593509905, 30209.395812083636, 30265.848670756506, 850.3962186847284, 853.8633818588996, 866.9869951950726, 905.0903841639242, 1101.5263436722125, 15523.0, 16190.0, 16364.83635163657, 16828.915855420888, 16902.154892255337, 398620.0, 400982.0, 403099.0, 404486.0, 404487.0, 54502.72748636232, 55109.724451377486, 55142.448575514536, 55243.447565524635, 55445.0, 1194.2118201986648, 1278.181963543333, 1294.3657607772311, 1298.3443361102773, 1353.2151352215494, 28785.136445906657, 30102.548461773527, 30203.395932080795, 30450.84774576113, 30660.540091898194, 43249.56750432456, 43698.56301436945, 43699.56300436955, 43874.0, 44088.33867491926, 316662.0, 319277.0, 320436.0, 320674.0, 320691.0, 22779.430514237276, 23516.529669406857, 25326.240212793644, 26091.478170436105, 26294.474110518066, 41614.58385416107, 42353.364699530146, 42532.5746742541, 43391.0, 43459.0, 36760.44859327059, 37927.81036094802, 38018.61981380151, 38057.42913856239, 38602.0, 37443.25113497769, 38699.2260154801, 40832.79583602063, 41803.0, 41879.58120418757, 37976.43035354527, 38986.415203772536, 39512.0, 39539.0, 39863.60136398599, 33949.49075763915, 35066.729393121954, 35122.12194695153, 35873.82063089668, 35925.461118083775, 26058.0, 26821.463570728865, 27132.72867271302, 27183.72816271891, 27236.05107566934], "value": 17549.27127685509, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.64507008482116, 390.1047096912586, 390.4514157608561, 402.95366032906225, 403.22983102274645, 16519.421820236294, 17484.82515174848, 18389.908050459744, 18447.354342598013, 18460.81539184606, 17512.649747005053, 17594.82405175948, 18344.449666510027, 18451.077446127707, 18454.16956236969, 5108.923366149511, 5224.686518808883, 5272.525472707455, 5297.735113244342, 5302.840914772543, 5256.92114618281, 5587.692676902769, 5595.21666966625, 5658.8019419320335, 5674.574406919477, 5593.8601534961745, 5631.8592035199035, 5632.746526406321, 5636.379998200195, 5637.915431268534, 6047.455728984391, 6050.092486127072, 6059.242594675666, 6075.119107729399, 6105.633661980295, 5243.764030618631, 5322.121849894763, 5390.568754499631, 5396.487333703292, 5403.486668766461, 13109.60671179866, 13334.599962001155, 18532.258709651596, 21216.575668486697, 21633.67549486743, 1961.3429501117143, 1995.6507611167988, 2057.609054279682, 2110.3879874836434, 2332.07882886259, 9578.0, 9909.108180263738, 10010.399376037461, 10894.18293627973, 13126.212427254395, 1767.6199617082389, 1839.705647096458, 1864.179760905211, 1890.7636545431822, 1899.135893168605, 63357.0, 63593.36406635921, 66571.3342866565, 71884.0, 74491.0, 10091.293609447268, 10211.897881021243, 10538.104261137736, 10563.683089507327, 10703.25077244601, 14357.066790658573, 14436.639084022983, 14442.855571444361, 14445.422183112558, 14469.276536173149, 7432.368248698921, 7672.578008209491, 7773.0, 7838.725644602411, 7869.331106855867, 7290.744823931137, 7423.183449820515, 7492.026036615205, 7506.136794268729, 7560.168381478033, 4432.800523976429, 4443.466783985878, 4485.0, 4661.160991021648, 4668.5564871337165, 1727.9654406912043, 1731.6190438103597, 1753.4914874686199, 1753.7807774028247, 1777.5289548269718, 942.8255772682076, 983.6606370802061, 987.8073775613686, 1001.4892404873422, 1032.2361452525226, 17097.48707538776, 17357.392491262744, 17452.301907923797, 17499.56251093733, 17760.200790964438, 860.5826174305457, 908.2370808520874, 951.3340661536952, 958.8274110660149, 968.1625394034251, 9553.856692149484, 9891.258155638356, 9917.553710083064, 9964.153046990941, 10063.345882517613, 418956.0, 426800.0, 427696.0, 434803.0, 435966.0, 78469.60765196252, 84452.0, 85908.14091859126, 86034.56982715172, 86045.56977215073, 927.7912469694264, 935.8876934767871, 949.8242825077383, 952.1288021460425, 955.9235261179228, 15338.386464541294, 15566.766498502759, 15625.531234062997, 15710.528684139494, 16080.758788618414, 33174.66825331812, 33288.83355583207, 34511.30977380388, 36136.0, 37614.0, 43988.0, 45276.0, 48708.756456217496, 50662.2400663983, 50763.23855142102, 6700.229473610597, 6967.1291088613925, 7088.893666594902, 7172.605506697182, 7329.1937886833475, 11061.778764424827, 12103.0, 12291.75416491683, 12297.754044919231, 12344.938275308567, 10737.194710396907, 10800.891991080303, 10851.620193293196, 10912.508937097691, 10926.78146437051, 10550.472476375997, 10630.946845265724, 10743.946280268548, 10758.462076895967, 10803.72990675208, 10754.0, 11062.834057488983, 11083.279586826831, 11135.0, 11141.554337826721, 6872.209695885037, 7152.284771522806, 7392.778216653509, 7394.519356241827, 7471.738489152854, 10189.184865210616, 10753.46232688378, 10806.891931080589, 10823.080038196842, 10885.945570272099], "value": 8489.600090772432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.0706584193545, 1477.4976507987283, 1516.2039929037257, 1702.8807983441156, 1877.6714075036873, 6760.222574403945, 7707.383409327254, 7946.443748937562, 7953.761387158387, 8193.057798353191, 7920.485168464046, 8097.352211823055, 8108.2297181767635, 8109.91890081099, 8110.635021424036, 2272.7272727272707, 2305.4236440889777, 2311.202635090891, 2313.560423519534, 2313.791758741713, 2256.8645881247176, 2281.0989659084653, 2287.370972982435, 2290.770922907704, 2291.9885400572975, 2290.816734661223, 2308.988455057722, 2321.3732292281074, 2340.918067867625, 2342.426105604121, 2243.0354947372625, 2245.5059886824874, 2247.3819699582664, 2250.2124256510174, 2250.7524172341027, 2200.8129309008755, 2204.1183526589493, 2206.9117235310728, 2208.6134926387817, 2212.23677831148, 7947.72182973599, 7966.681332746683, 8015.398845086584, 8028.438009339348, 8040.758777236692, 4851.490593487668, 4987.301777751081, 4989.800407983676, 4995.525425084611, 4996.675216110977, 2959.6942908113174, 3019.667836537979, 3053.7709671774705, 3071.063325685665, 3089.845507724605, 896.2829736211005, 923.1230331185334, 938.436937837299, 943.0804965158984, 943.5235206220815, 10846.67459976202, 10892.0, 10896.291741036806, 10918.563257469692, 10945.781084378348, 4201.537830838605, 4292.248856450171, 4359.498657654411, 4363.781810909441, 4376.956230437719, 8657.350698697623, 8719.956400217958, 8721.956390218009, 8731.388802783744, 8731.869021964676, 4731.550502702238, 4752.572268495852, 4755.405574303212, 4774.618030557547, 4781.95218047822, 3714.795686237229, 4284.186004659105, 4384.605385515319, 4393.714408563433, 4410.316400957857, 3602.405603075463, 3613.9277214456088, 3683.5211422515436, 3707.703383729295, 3726.9440958385644, 1098.3739268616812, 1100.9339439633488, 1104.4256986367045, 1112.4437781109445, 1116.1294190531398, 680.1328306409367, 704.8167476456056, 750.6096829648552, 815.4210510537584, 861.6079683743882, 5081.18701007837, 5085.5168758968575, 5429.158480435539, 5470.890582188334, 5497.367802702661, 857.9485230886166, 904.4302089683457, 905.1762895764954, 940.5532372123187, 958.242988039455, 3471.9132021699156, 3681.2637472505608, 3692.593814680383, 3711.870084547028, 3712.0, 9471.10024547667, 9593.712188634212, 9901.801963960825, 9903.603855845686, 9914.752131196778, 9763.414195148314, 9804.36271642341, 9834.557444914995, 9844.114029737359, 9846.310758246855, 7298.197198308182, 7382.335589796837, 7434.73978410753, 7497.212792656802, 7507.099148102154, 5353.330833645794, 5385.488378604026, 5387.955577341071, 5406.702631355156, 5426.0, 9147.451152930846, 9181.357304988587, 9252.907470925205, 9258.95370523143, 9266.953665231631, 8619.482831030158, 8813.383063185516, 8926.509042002752, 9147.0, 9301.534923253945, 7049.29507049291, 7209.567425954249, 7217.530860494051, 7221.205667376689, 7235.312645298688, 7716.305532502101, 7780.0, 7784.844303113793, 7790.143084260613, 7791.220877912164, 5544.41783612731, 6817.42051925572, 6817.795466136024, 6845.794626161223, 7000.50996430245, 6025.517958563392, 6624.834379140368, 6689.2307384651385, 6805.217399998865, 6893.724251029903, 6495.967520162369, 6511.47908167336, 6547.639879806466, 6551.44312733423, 6587.9670601646685, 4300.526942036436, 4513.819447222074, 4581.816727331003, 4599.931001035054, 4714.905701885875, 4631.791569379318, 4677.649176311722, 4775.283707443911, 4784.186688262934, 4804.927926081182], "value": 4221.0449361989695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.50152260950307, 320.9823459709715, 324.4387210126481, 327.8033180091945, 330.98345082745857, 4810.08608364411, 4850.078485087835, 4855.368802055732, 4857.635677324197, 4894.650567727493, 4783.402074740658, 4799.7360145192, 4815.0610630926985, 4817.831375901844, 4850.781714822832, 3664.4320130379765, 3750.9624903750887, 3776.527934008249, 3879.495665563487, 3881.5342158940825, 3811.5235595550557, 3858.59484754101, 3866.4534186325554, 3872.051347419886, 3875.360565506702, 3816.5992570780086, 3819.2743378758087, 3830.693544516432, 3839.827207775643, 3880.611938806125, 3664.175560498893, 3692.796896170723, 3794.6584807367194, 3841.154945911895, 3847.7306588538813, 3384.526166336715, 3394.62659107498, 3423.298223864102, 3427.4687423449404, 3431.759776815624, 4627.213373726475, 4667.22990706533, 4674.368960190399, 4683.789229484647, 4699.412573428322, 4280.785960701984, 4378.190034843564, 4388.517263101056, 4391.934120988188, 4394.736315821061, 5038.470960549126, 5051.848444546669, 5058.266551350071, 5070.594352451832, 5073.797048118071, 3730.0, 3730.7388482806214, 3732.7387082904206, 3749.1751814600743, 3766.7551609145316, 5076.568491678212, 5282.31329927107, 5320.361556613192, 5336.733163341817, 5346.705931173784, 3824.196918647115, 3831.904202394907, 3850.5764365919727, 3862.6909847212155, 3867.3038853006165, 3895.7078219133678, 3920.5491368492417, 3924.4701965234326, 3927.430522574211, 3932.803359832054, 4352.499462561783, 4362.716423432466, 4365.388845561624, 4381.693281470267, 4396.934045989313, 4834.806607735652, 5088.949110508847, 5094.0, 5109.948900510947, 5122.9743851281255, 1291.6125162451415, 1304.3673818198356, 1304.7716649586284, 1310.2924420812653, 1314.2574445438297, 807.463037080335, 814.9511029338258, 846.1284876577013, 852.2500199824115, 855.5679381912095, 4415.801288942005, 4455.6212721918355, 4497.685162038626, 4507.061963355723, 4533.52397998212, 5243.790248390098, 5264.499872512182, 5277.076511610453, 5281.0, 5282.6302158849285, 4514.413126293561, 4517.186906356822, 4520.141173177086, 4532.0, 4545.477270113913, 2651.5890037044287, 2799.678037025768, 2813.184176588807, 2817.788036391423, 2837.0, 4883.438404583519, 4934.333864928189, 4941.925871111936, 4989.750512474362, 4999.875003124951, 5241.554467870197, 5381.412483317431, 5383.300170977748, 5391.0, 5408.512659018801, 4972.129877271463, 4976.576990955737, 4993.275974983607, 5005.399352077774, 5015.5736762374145, 5308.601854860978, 5321.3348331458565, 5331.70675612845, 5336.557920141565, 5344.919826202532, 4995.200767877122, 5208.765605547684, 5208.8437346879655, 5217.0, 5225.738713064407, 4925.817803727008, 4959.776810043484, 5021.5229553192385, 5028.170351892043, 5045.596352291735, 3406.84669189892, 3433.8798142064907, 3471.8821518873974, 3482.5820901491984, 3499.580050393969, 4566.954330456653, 4737.644676649196, 4769.57073863354, 4787.880302992453, 4798.760061996816, 4591.760224739235, 4599.67802253839, 4602.907941841211, 4603.457841623093, 4621.682820396171, 4559.065391594649, 4597.7011494252765, 4613.607843333354, 4620.745858977789, 4621.884452888705, 4422.0, 4532.478764942074, 4555.658325625657, 4565.0, 4627.9768601156775, 4651.348811166372, 4932.926006109983, 5058.822941197041, 5095.668781529189, 5104.0, 4927.28554359623, 4986.202207646759, 4990.850274491771, 5050.696958182521, 5077.466865979019], "value": 3815.340679506833, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.3528988656286, 779.5244900610627, 780.1769133564088, 785.579714852554, 794.6185830801215, 10466.215033872451, 12163.148579599432, 12347.691307717312, 12368.0, 12412.06909481789, 12189.207701499396, 12336.81494777579, 12343.814842777365, 12408.627741167757, 12504.437300321484, 680.8638272345526, 832.9583520823965, 865.6753717356007, 872.6989188729909, 877.8156587116713, 869.5304535550795, 878.6748902905933, 879.1911441473827, 879.4283715584857, 882.5190271302124, 914.1635403605693, 917.2845180759018, 917.3716004536881, 919.797644518205, 920.397139873384, 704.436450839329, 886.0829041941624, 906.7687739626354, 908.0556221529602, 909.4316052467208, 679.5752654590881, 680.0717021265986, 680.8331958670108, 681.0567364200618, 681.6216999565289, 13145.86854131456, 13170.078094533386, 13192.076554641179, 13196.076274660778, 13201.867981320256, 11960.64118076459, 12465.501379944792, 12660.502825648491, 12729.618111456579, 12768.553100641433, 9989.700308990741, 10900.618478353299, 11040.447977601169, 11151.163662725246, 11169.609063682814, 4123.195976784528, 4176.373543968399, 4221.91556168878, 4240.957590424087, 4298.419713338691, 50233.0, 50259.0, 50327.0, 50648.49351506474, 50820.23769643495, 10113.898861011443, 10227.181825453947, 10282.228832837567, 10283.640072597424, 10396.48017599132, 17918.820811791975, 18127.72808407875, 18139.90930045341, 18142.63714725698, 18450.815491845176, 6361.332060133619, 6480.384363485461, 6488.383603557654, 6495.285518592951, 6521.217453905561, 3837.175007373428, 3854.5759966403675, 3908.863189788344, 3937.7637341759587, 3951.723379363417, 2794.413173233603, 2853.7288957549, 2854.6288982432566, 2855.029290041354, 2866.226118947914, 502.311832789074, 510.89015861589934, 511.1770050219144, 547.479894100606, 577.7313549199642, 483.72185993054137, 624.4130517313711, 639.4468784501465, 639.7664852328867, 647.1102234427661, 8202.227679789683, 8567.914320856837, 8573.22840944318, 8649.956750216334, 8705.0, 534.2067030459764, 580.1703563903658, 600.6336134957672, 611.4710775179437, 613.6318209074609, 3957.485526881487, 4087.0, 4120.62914337711, 4146.0, 4193.874183774492, 48279.0, 54739.17891231635, 55539.72230138823, 55667.0, 55830.44169558252, 50486.495135048914, 50770.74614626903, 51083.7445812776, 51455.0, 52140.739296303276, 47331.0, 47812.28281575779, 48491.0, 48546.75726621344, 48845.99999999929, 6911.3779759821855, 6918.688659010458, 7007.859842803217, 7124.715011399486, 7189.460790440842, 30443.391132177676, 30741.8462907693, 30875.9999999991, 32835.67164328326, 33009.0, 42007.78996105, 42344.364834528125, 42414.78792606017, 42507.0, 44107.0, 11131.387773672526, 11135.610253641084, 11146.88853111491, 11514.481848316678, 11619.244749091282, 12396.690082747642, 12465.5013799447, 12556.87443125557, 12984.091113622333, 13177.209367437985, 11846.585369512026, 11862.703432414259, 12060.396980151132, 12430.937845311077, 12595.0, 11559.0, 11796.528138874692, 12070.879291206975, 12144.696382590506, 12172.2696638202, 11630.88369116298, 11663.650090497298, 11681.474333654836, 12242.0, 12265.362946966445, 9488.762780930532, 9528.904710952802, 9678.612855485702, 9788.706338809847, 9907.405555666683, 9615.51922403891, 9664.9033509664, 9692.75768105803, 9811.215102791617, 9838.557264923238], "value": 6246.5557553496365, "unit": "score"}], "name": "dromaeo_css", "value": 6407.057097219902, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 114.0, 115.0, 113.0, 111.0, 112.0, 113.0, 114.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 129.0, 154.0, 217.0, 135.0, 155.0, 152.0, 131.0, 198.0, 130.0], "value": 156.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 162.0, 161.0, 162.0, 162.0, 161.0, 161.0, 161.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 157.0, 76.0, 75.0, 77.0, 75.0, 130.0, 75.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 93.0, 92.0, 94.0, 124.0, 93.0, 93.0, 139.0], "value": 106.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 63.0, 66.0, 65.0, 65.0, 67.0, 63.0, 63.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 63.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 82.0, 83.0, 84.0, 82.0, 84.0, 84.0, 142.0, 89.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 142.0, 137.0, 132.0, 146.0, 129.0, 129.0, 130.0, 152.0, 130.0], "value": 135.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 170.0, 180.0, 170.0, 176.0, 173.0, 172.0, 174.0, 174.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 70.0, 67.0, 67.0, 66.0, 65.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1579.3999999999999, "alertThreshold": 2.0}]} 05:49:07 INFO - Return code: 0 05:49:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:07 ERROR - # TBPL SUCCESS # 05:49:07 INFO - Running post-action listener: _resource_record_post_action 05:49:07 INFO - ##### 05:49:07 INFO - ##### Finished run-tests step (success) 05:49:07 INFO - ##### 05:49:07 INFO - Running post-run listener: _resource_record_post_run 05:49:07 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 531599360; Read time: 20; Write time: 645624 05:49:07 INFO - TinderboxPrint: CPU usage
13.2% 05:49:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 05:49:07 INFO - TinderboxPrint: I/O write bytes / time
531,599,360 / 645,624 05:49:07 INFO - TinderboxPrint: CPU idle
8,321.6 (86.8%) 05:49:07 INFO - TinderboxPrint: CPU user
1,217.7 (12.7%) 05:49:07 INFO - TinderboxPrint: Swap in / out
0 / 0 05:49:07 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 226549760; Read time: 0; Write time: 376064 05:49:07 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295399424; Read time: 20; Write time: 268408 05:49:08 INFO - Running post-run listener: _upload_blobber_files 05:49:08 INFO - Blob upload gear active. 05:49:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:49:08 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 05:49:08 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'] 05:49:08 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 05:49:08 INFO - (blobuploader) - INFO - Open directory for files ... 05:49:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:49:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:49:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:14 INFO - (blobuploader) - INFO - Done attempting. 05:49:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:49:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:15 INFO - (blobuploader) - INFO - Done attempting. 05:49:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:49:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:49:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:16 INFO - (blobuploader) - INFO - Done attempting. 05:49:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:49:16 INFO - Return code: 0 05:49:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:49:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:49:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88941a9b358e4d0fbe02b3cc60c4fd703c5607ee463ef81c3a4361e6585ee20a1de98a01eec8c5403d1dc30fea852bb5139515580c70e00c673a8dda5b65062d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39bc2b47e3a81b613cedd4ab263190145ed2642a48f0f14a7b2b189c643c9091f8720fbb1a5e8894d28c6c29f389cfa97b7f4439098be9d2eecf1a961522f33b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6098d39495e3fb12db3d30d96e2ebfd4b6936db739a4cec4929f90688f185a01d185f5de59bbf12c096b81cca5e74b91bcf8332e1c2886bb8c44a6a5cb0652b1"} 05:49:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:49:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:49:16 INFO - Contents: 05:49:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88941a9b358e4d0fbe02b3cc60c4fd703c5607ee463ef81c3a4361e6585ee20a1de98a01eec8c5403d1dc30fea852bb5139515580c70e00c673a8dda5b65062d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39bc2b47e3a81b613cedd4ab263190145ed2642a48f0f14a7b2b189c643c9091f8720fbb1a5e8894d28c6c29f389cfa97b7f4439098be9d2eecf1a961522f33b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6098d39495e3fb12db3d30d96e2ebfd4b6936db739a4cec4929f90688f185a01d185f5de59bbf12c096b81cca5e74b91bcf8332e1c2886bb8c44a6a5cb0652b1"} 05:49:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:49:16 INFO - Copying logs to upload dir... 05:49:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:49:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.981046 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-17 05:49:15.386735) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:49:15.390108) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 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/88941a9b358e4d0fbe02b3cc60c4fd703c5607ee463ef81c3a4361e6585ee20a1de98a01eec8c5403d1dc30fea852bb5139515580c70e00c673a8dda5b65062d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39bc2b47e3a81b613cedd4ab263190145ed2642a48f0f14a7b2b189c643c9091f8720fbb1a5e8894d28c6c29f389cfa97b7f4439098be9d2eecf1a961522f33b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6098d39495e3fb12db3d30d96e2ebfd4b6936db739a4cec4929f90688f185a01d185f5de59bbf12c096b81cca5e74b91bcf8332e1c2886bb8c44a6a5cb0652b1"} build_url:https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/SCs880ZaSqitwvq6IEVjaQ/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/88941a9b358e4d0fbe02b3cc60c4fd703c5607ee463ef81c3a4361e6585ee20a1de98a01eec8c5403d1dc30fea852bb5139515580c70e00c673a8dda5b65062d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39bc2b47e3a81b613cedd4ab263190145ed2642a48f0f14a7b2b189c643c9091f8720fbb1a5e8894d28c6c29f389cfa97b7f4439098be9d2eecf1a961522f33b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6098d39495e3fb12db3d30d96e2ebfd4b6936db739a4cec4929f90688f185a01d185f5de59bbf12c096b81cca5e74b91bcf8332e1c2886bb8c44a6a5cb0652b1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:49:15.414471) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:49:15.414774) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1463487606.609323-229330245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004491 ========= master_lag: 0.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:49:16.079780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:49:16.080092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:49:16.080917) ========= ========= Total master_lag: 0.74 =========