builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1463504838.65 results: success (0) buildid: 20160517073121 builduid: 91748e3a507046789edb563a3e6ea041 revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.650748) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.651254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.651574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.668154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.668584) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 10:07:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.0M=0s 2016-05-17 10:07:18 (29.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077722 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.760292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.760639) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016563 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:18.809927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:07:18.810247) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --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 3780a3a6b83aeda143f9562829c830410a0c961e --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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 10:07:18,948 truncating revision to first 12 chars 2016-05-17 10:07:18,948 Setting DEBUG logging. 2016-05-17 10:07:18,948 attempt 1/10 2016-05-17 10:07:18,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 10:07:20,324 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.687436 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:07:20.510463) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:20.510817) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:20.511240) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:20.511540) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:07:20.519560) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-17 10:07:20.519890) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:07:20 INFO - MultiFileLogger online at 20160517 10:07:20 in /builds/slave/test 10:07:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:07:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:07:20 INFO - {'append_to_log': False, 10:07:20 INFO - 'base_work_dir': '/builds/slave/test', 10:07:20 INFO - 'blob_upload_branch': 'Firefox', 10:07:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:07:20 INFO - 'branch': 'Firefox', 10:07:20 INFO - 'buildbot_json_path': 'buildprops.json', 10:07:20 INFO - 'config_files': ('talos/linux_config.py',), 10:07:20 INFO - 'default_actions': ('clobber', 10:07:20 INFO - 'read-buildbot-config', 10:07:20 INFO - 'download-and-extract', 10:07:20 INFO - 'populate-webroot', 10:07:20 INFO - 'create-virtualenv', 10:07:20 INFO - 'install', 10:07:20 INFO - 'run-tests'), 10:07:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:07:20 INFO - 'download_minidump_stackwalk': True, 10:07:20 INFO - 'download_symbols': 'ondemand', 10:07:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:07:20 INFO - 'tooltool.py': '/tools/tooltool.py', 10:07:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:07:20 INFO - '/tools/misc-python/virtualenv.py')}, 10:07:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:07:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:07:20 INFO - 'installer_path': 'installer.exe', 10:07:20 INFO - 'log_level': 'info', 10:07:20 INFO - 'log_name': 'talos', 10:07:20 INFO - 'log_to_console': True, 10:07:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:07:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:07:20 INFO - 'opt_config_files': (), 10:07:20 INFO - 'pip_index': False, 10:07:20 INFO - 'sps_profile': False, 10:07:20 INFO - 'sps_profile_interval': 0, 10:07:20 INFO - 'suite': 'dromaeojs', 10:07:20 INFO - 'system_bits': '32', 10:07:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:07:20 INFO - 'title': 'talos-linux64-ix-006', 10:07:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:07:20 INFO - 'use_talos_json': True, 10:07:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:07:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:07:20 INFO - 'work_dir': 'build'} 10:07:20 INFO - ##### 10:07:20 INFO - ##### Running clobber step. 10:07:20 INFO - ##### 10:07:20 INFO - Running pre-action listener: _resource_record_pre_action 10:07:20 INFO - Running main action method: clobber 10:07:20 INFO - rmtree: /builds/slave/test/build 10:07:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:07:21 INFO - Running post-action listener: _resource_record_post_action 10:07:21 INFO - ##### 10:07:21 INFO - ##### Finished clobber step (success) 10:07:21 INFO - ##### 10:07:21 INFO - ##### 10:07:21 INFO - ##### Running read-buildbot-config step. 10:07:21 INFO - ##### 10:07:21 INFO - Running pre-action listener: _resource_record_pre_action 10:07:21 INFO - Running main action method: read_buildbot_config 10:07:21 INFO - Using buildbot properties: 10:07:21 INFO - { 10:07:21 INFO - "project": "", 10:07:21 INFO - "product": "firefox", 10:07:21 INFO - "script_repo_revision": "production", 10:07:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:07:21 INFO - "repository": "", 10:07:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:07:21 INFO - "buildid": "20160517073121", 10:07:21 INFO - "pgo_build": "True", 10:07:21 INFO - "basedir": "/builds/slave/test", 10:07:21 INFO - "buildnumber": 289, 10:07:21 INFO - "slavename": "talos-linux64-ix-006", 10:07:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:07:21 INFO - "platform": "ubuntu64_hw", 10:07:21 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 10:07:21 INFO - "branch": "mozilla-central", 10:07:21 INFO - "repo_path": "mozilla-central", 10:07:21 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 10:07:21 INFO - "stage_platform": "linux64-pgo", 10:07:21 INFO - "builduid": "91748e3a507046789edb563a3e6ea041", 10:07:21 INFO - "slavebuilddir": "test" 10:07:21 INFO - } 10:07:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:07:21 INFO - Running post-action listener: _resource_record_post_action 10:07:21 INFO - ##### 10:07:21 INFO - ##### Finished read-buildbot-config step (success) 10:07:21 INFO - ##### 10:07:21 INFO - ##### 10:07:21 INFO - ##### Running download-and-extract step. 10:07:21 INFO - ##### 10:07:21 INFO - Running pre-action listener: _resource_record_pre_action 10:07:21 INFO - Running main action method: download_and_extract 10:07:21 INFO - mkdir: /builds/slave/test/build/tests 10:07: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')]} 10:07:21 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:07:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:07:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:07:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:22 INFO - Downloaded 1361 bytes. 10:07:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:07:22 INFO - Using the following test package requirements: 10:07:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:07:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:07:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:07:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'jsshell-linux-x86_64.zip'], 10:07:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:07:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:07:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:07:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:07:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:07:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:07:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:07:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:22 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:07:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:07:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:07:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:24 INFO - Downloaded 24023024 bytes. 10:07:24 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'] 10:07:24 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 10:07:25 INFO - Return code: 0 10:07:25 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 10:07:25 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')]} 10:07:25 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:07:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:07:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:07:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:31 INFO - Downloaded 24023024 bytes. 10:07:31 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'] 10:07:31 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 10:07:33 INFO - Return code: 0 10:07:33 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')]} 10:07:33 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:07:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:07:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:07:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:34 INFO - Downloaded 10840194 bytes. 10:07:34 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'] 10:07:34 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 10:07:34 INFO - Return code: 0 10:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:34 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:07:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:07:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:07:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 10:07:36 INFO - Downloaded 58399391 bytes. 10:07:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:07:36 INFO - mkdir: /builds/slave/test/properties 10:07:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:07:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:07:36 INFO - Contents: 10:07:36 INFO - build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:07:36 INFO - Running post-action listener: _resource_record_post_action 10:07:36 INFO - Running post-action listener: set_extra_try_arguments 10:07:36 INFO - ##### 10:07:36 INFO - ##### Finished download-and-extract step (success) 10:07:36 INFO - ##### 10:07:36 INFO - ##### 10:07:36 INFO - ##### Running populate-webroot step. 10:07:36 INFO - ##### 10:07:36 INFO - Running pre-action listener: _resource_record_pre_action 10:07:36 INFO - Running main action method: populate_webroot 10:07:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:07:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:07:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:07:36 INFO - '--tppagecycles', 10:07:36 INFO - '7'], 10:07:36 INFO - 'tests': ['tsvgm']}}, 10:07:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:07:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:07:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:07:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:07:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['damp', 'tps']}, 10:07:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'tests': ['damp', 'tps']}, 10:07:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['dromaeo_dom']}, 10:07:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:07:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'other-e10s': {'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['a11yr', 10:07:36 INFO - 'ts_paint', 10:07:36 INFO - 'tpaint', 10:07:36 INFO - 'sessionrestore', 10:07:36 INFO - 'sessionrestore_no_auto_restore', 10:07:36 INFO - 'tabpaint']}, 10:07:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['tsvgx', 10:07:36 INFO - 'tsvgr_opacity', 10:07:36 INFO - 'tart', 10:07:36 INFO - 'tscrollx', 10:07:36 INFO - 'cart']}, 10:07:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:07:36 INFO - 'tsvgr_opacity', 10:07:36 INFO - 'tart', 10:07:36 INFO - 'tscrollx', 10:07:36 INFO - 'cart']}, 10:07:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'talos_options': ['--disable-e10s'], 10:07:36 INFO - 'tests': ['tp5o']}, 10:07:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'tests': ['tp5o']}, 10:07:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'talos_options': ['--disable-e10s', 10:07:36 INFO - '--xperf_path', 10:07:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:36 INFO - 'tests': ['tp5n']}, 10:07:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:36 INFO - 'talos_options': ['--xperf_path', 10:07:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:36 INFO - 'tests': ['tp5n']}}, 10:07:36 INFO - 'talos.zip': {'path': '', 10:07:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:07:36 INFO - Running post-action listener: _resource_record_post_action 10:07:36 INFO - ##### 10:07:36 INFO - ##### Finished populate-webroot step (success) 10:07:36 INFO - ##### 10:07:36 INFO - ##### 10:07:36 INFO - ##### Running create-virtualenv step. 10:07:36 INFO - ##### 10:07:36 INFO - Running pre-action listener: _resource_record_pre_action 10:07:36 INFO - Running main action method: create_virtualenv 10:07:36 INFO - Creating virtualenv /builds/slave/test/build/venv 10:07:36 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 10:07:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:07:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:07:36 INFO - Using real prefix '/usr' 10:07:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:07:37 INFO - Installing distribute.............................................................................................................................................................................................done. 10:07:39 INFO - Installing pip.................done. 10:07:39 INFO - Return code: 0 10:07:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:07:39 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')]} 10:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:39 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')]} 10:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:39 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:39 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 10:07:39 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 10:07:39 INFO - Using env: {'DISPLAY': ':0', 10:07:39 INFO - 'HOME': '/home/cltbld', 10:07:39 INFO - 'LANG': 'en_US.UTF-8', 10:07:39 INFO - 'LANGUAGE': 'en_US:en', 10:07:39 INFO - 'LOGNAME': 'cltbld', 10:07:39 INFO - 'MAIL': '/var/mail/cltbld', 10:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:39 INFO - 'MOZ_NO_REMOTE': '1', 10:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:39 INFO - 'NO_EM_RESTART': '1', 10:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:39 INFO - 'PWD': '/builds/slave/test', 10:07:39 INFO - 'SHELL': '/bin/bash', 10:07:39 INFO - 'SHLVL': '1', 10:07:39 INFO - 'TERM': 'linux', 10:07:39 INFO - 'TMOUT': '86400', 10:07:39 INFO - 'USER': 'cltbld', 10:07:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:39 INFO - '_': '/tools/buildbot/bin/python'} 10:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:39 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 10:07:39 INFO - Cleaning up... 10:07:39 INFO - Return code: 0 10:07:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:07:39 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')]} 10:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:39 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')]} 10:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:39 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:39 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 10:07:39 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 10:07:39 INFO - Using env: {'DISPLAY': ':0', 10:07:39 INFO - 'HOME': '/home/cltbld', 10:07:39 INFO - 'LANG': 'en_US.UTF-8', 10:07:39 INFO - 'LANGUAGE': 'en_US:en', 10:07:39 INFO - 'LOGNAME': 'cltbld', 10:07:39 INFO - 'MAIL': '/var/mail/cltbld', 10:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:39 INFO - 'MOZ_NO_REMOTE': '1', 10:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:39 INFO - 'NO_EM_RESTART': '1', 10:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:39 INFO - 'PWD': '/builds/slave/test', 10:07:39 INFO - 'SHELL': '/bin/bash', 10:07:39 INFO - 'SHLVL': '1', 10:07:39 INFO - 'TERM': 'linux', 10:07:39 INFO - 'TMOUT': '86400', 10:07:39 INFO - 'USER': 'cltbld', 10:07:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:39 INFO - '_': '/tools/buildbot/bin/python'} 10:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:39 INFO - Downloading/unpacking psutil>=3.1.1 10:07:39 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 10:07:39 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 10:07:39 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 10:07:39 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 10:07:39 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 10:07:39 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 10:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:07:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:41 INFO - Installing collected packages: psutil 10:07:41 INFO - Running setup.py install for psutil 10:07:41 INFO - building 'psutil._psutil_linux' extension 10:07:41 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 10:07:42 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 10:07:42 INFO - building 'psutil._psutil_posix' extension 10:07:42 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 10:07:42 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 10:07:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:42 INFO - Successfully installed psutil 10:07:42 INFO - Cleaning up... 10:07:42 INFO - Return code: 0 10:07:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:07:42 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')]} 10:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:42 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')]} 10:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:42 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:42 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 10:07:42 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 10:07:42 INFO - Using env: {'DISPLAY': ':0', 10:07:42 INFO - 'HOME': '/home/cltbld', 10:07:42 INFO - 'LANG': 'en_US.UTF-8', 10:07:42 INFO - 'LANGUAGE': 'en_US:en', 10:07:42 INFO - 'LOGNAME': 'cltbld', 10:07:42 INFO - 'MAIL': '/var/mail/cltbld', 10:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:42 INFO - 'MOZ_NO_REMOTE': '1', 10:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:42 INFO - 'NO_EM_RESTART': '1', 10:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:42 INFO - 'PWD': '/builds/slave/test', 10:07:42 INFO - 'SHELL': '/bin/bash', 10:07:42 INFO - 'SHLVL': '1', 10:07:42 INFO - 'TERM': 'linux', 10:07:42 INFO - 'TMOUT': '86400', 10:07:42 INFO - 'USER': 'cltbld', 10:07:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:42 INFO - '_': '/tools/buildbot/bin/python'} 10:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:07:42 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 10:07:42 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 10:07:42 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 10:07:42 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 10:07:42 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 10:07:42 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 10:07:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:07:44 INFO - Installing collected packages: mozsystemmonitor 10:07:44 INFO - Running setup.py install for mozsystemmonitor 10:07:44 INFO - Successfully installed mozsystemmonitor 10:07:44 INFO - Cleaning up... 10:07:44 INFO - Return code: 0 10:07:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:07: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')]} 10:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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')]} 10:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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', '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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:07: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 blobuploader==1.2.4 10:07:44 INFO - Using env: {'DISPLAY': ':0', 10:07:44 INFO - 'HOME': '/home/cltbld', 10:07:44 INFO - 'LANG': 'en_US.UTF-8', 10:07:44 INFO - 'LANGUAGE': 'en_US:en', 10:07:44 INFO - 'LOGNAME': 'cltbld', 10:07:44 INFO - 'MAIL': '/var/mail/cltbld', 10:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:44 INFO - 'MOZ_NO_REMOTE': '1', 10:07:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:44 INFO - 'NO_EM_RESTART': '1', 10:07:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:44 INFO - 'PWD': '/builds/slave/test', 10:07:44 INFO - 'SHELL': '/bin/bash', 10:07:44 INFO - 'SHLVL': '1', 10:07:44 INFO - 'TERM': 'linux', 10:07:44 INFO - 'TMOUT': '86400', 10:07:44 INFO - 'USER': 'cltbld', 10:07:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:44 INFO - '_': '/tools/buildbot/bin/python'} 10:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:44 INFO - Downloading/unpacking blobuploader==1.2.4 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:46 INFO - Downloading blobuploader-1.2.4.tar.gz 10:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:07:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:07:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 INFO - Downloading docopt-0.6.1.tar.gz 10:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:07:47 INFO - Installing collected packages: blobuploader, requests, docopt 10:07:47 INFO - Running setup.py install for blobuploader 10:07:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:07:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:07:47 INFO - Running setup.py install for requests 10:07:48 INFO - Running setup.py install for docopt 10:07:48 INFO - Successfully installed blobuploader requests docopt 10:07:48 INFO - Cleaning up... 10:07:48 INFO - Return code: 0 10:07:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:48 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:48 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 10:07:48 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 10:07:48 INFO - Using env: {'DISPLAY': ':0', 10:07:48 INFO - 'HOME': '/home/cltbld', 10:07:48 INFO - 'LANG': 'en_US.UTF-8', 10:07:48 INFO - 'LANGUAGE': 'en_US:en', 10:07:48 INFO - 'LOGNAME': 'cltbld', 10:07:48 INFO - 'MAIL': '/var/mail/cltbld', 10:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:48 INFO - 'MOZ_NO_REMOTE': '1', 10:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:48 INFO - 'NO_EM_RESTART': '1', 10:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:48 INFO - 'PWD': '/builds/slave/test', 10:07:48 INFO - 'SHELL': '/bin/bash', 10:07:48 INFO - 'SHLVL': '1', 10:07:48 INFO - 'TERM': 'linux', 10:07:48 INFO - 'TMOUT': '86400', 10:07:48 INFO - 'USER': 'cltbld', 10:07:48 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:48 INFO - '_': '/tools/buildbot/bin/python'} 10:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:07:48 INFO - Running setup.py (path:/tmp/pip-IGe8jR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:07:48 INFO - Running setup.py (path:/tmp/pip-7tnOEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:07:48 INFO - Running setup.py (path:/tmp/pip-8fV4cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:07:48 INFO - Running setup.py (path:/tmp/pip-grJmmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:07:48 INFO - Running setup.py (path:/tmp/pip-hNIEPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:07:48 INFO - Running setup.py (path:/tmp/pip-k_Biga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:07:48 INFO - Running setup.py (path:/tmp/pip-U68AB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:07:48 INFO - Running setup.py (path:/tmp/pip-701xOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:07:48 INFO - Running setup.py (path:/tmp/pip-VDvzZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:07:49 INFO - Running setup.py (path:/tmp/pip-gE4mmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:07:49 INFO - Running setup.py (path:/tmp/pip-QOK7nX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:07:49 INFO - Running setup.py (path:/tmp/pip-S9skcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:07:49 INFO - Running setup.py (path:/tmp/pip-8RmVun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:07:49 INFO - Running setup.py (path:/tmp/pip-VKQMsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:49 INFO - Running setup.py (path:/tmp/pip-sS2YSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:07:49 INFO - Running setup.py (path:/tmp/pip-WnuDXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:07:49 INFO - Running setup.py (path:/tmp/pip-kX6GaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:07:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:07:49 INFO - Running setup.py install for manifestparser 10:07:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Running setup.py install for mozcrash 10:07:49 INFO - Running setup.py install for mozdebug 10:07:49 INFO - Running setup.py install for mozdevice 10:07:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozfile 10:07:50 INFO - Running setup.py install for mozhttpd 10:07:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozinfo 10:07:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozInstall 10:07:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozleak 10:07:50 INFO - Running setup.py install for mozlog 10:07:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for moznetwork 10:07:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozprocess 10:07:50 INFO - Running setup.py install for mozprofile 10:07:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozrunner 10:07:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:07:51 INFO - Running setup.py install for mozscreenshot 10:07:51 INFO - Running setup.py install for moztest 10:07:51 INFO - Running setup.py install for mozversion 10:07:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:07:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:07:51 INFO - Cleaning up... 10:07:51 INFO - Return code: 0 10:07:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:51 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:51 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 10:07:51 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 10:07:51 INFO - Using env: {'DISPLAY': ':0', 10:07:51 INFO - 'HOME': '/home/cltbld', 10:07:51 INFO - 'LANG': 'en_US.UTF-8', 10:07:51 INFO - 'LANGUAGE': 'en_US:en', 10:07:51 INFO - 'LOGNAME': 'cltbld', 10:07:51 INFO - 'MAIL': '/var/mail/cltbld', 10:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:51 INFO - 'MOZ_NO_REMOTE': '1', 10:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:51 INFO - 'NO_EM_RESTART': '1', 10:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:51 INFO - 'PWD': '/builds/slave/test', 10:07:51 INFO - 'SHELL': '/bin/bash', 10:07:51 INFO - 'SHLVL': '1', 10:07:51 INFO - 'TERM': 'linux', 10:07:51 INFO - 'TMOUT': '86400', 10:07:51 INFO - 'USER': 'cltbld', 10:07:51 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:51 INFO - '_': '/tools/buildbot/bin/python'} 10:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:07:51 INFO - Running setup.py (path:/tmp/pip-8kCpZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:07:51 INFO - Running setup.py (path:/tmp/pip-yurvfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:07:51 INFO - Running setup.py (path:/tmp/pip-Q1w1aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:07:51 INFO - Running setup.py (path:/tmp/pip-Djenwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:07:51 INFO - Running setup.py (path:/tmp/pip-bliuij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:07:51 INFO - Running setup.py (path:/tmp/pip-YVOl_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:07:52 INFO - Running setup.py (path:/tmp/pip-piizg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:07:52 INFO - Running setup.py (path:/tmp/pip-8ImnP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:07:52 INFO - Running setup.py (path:/tmp/pip-UqfMWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:07:52 INFO - Running setup.py (path:/tmp/pip-iLaIgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:07:52 INFO - Running setup.py (path:/tmp/pip-1ys_HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:07:52 INFO - Running setup.py (path:/tmp/pip-lB00mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:07:52 INFO - Running setup.py (path:/tmp/pip-rY5Z48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:07:52 INFO - Running setup.py (path:/tmp/pip-rM32Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:52 INFO - Running setup.py (path:/tmp/pip-IeJKaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:07:52 INFO - Running setup.py (path:/tmp/pip-GOzZBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:07:52 INFO - Running setup.py (path:/tmp/pip-UfbP0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:54 INFO - Downloading blessings-1.6.tar.gz 10:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:07:54 INFO - Installing collected packages: blessings 10:07:54 INFO - Running setup.py install for blessings 10:07:54 INFO - Successfully installed blessings 10:07:54 INFO - Cleaning up... 10:07:54 INFO - Return code: 0 10:07:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:07:55 INFO - Reading from file tmpfile_stdout 10:07:55 INFO - Current package versions: 10:07:55 INFO - argparse == 1.2.1 10:07:55 INFO - blessings == 1.6 10:07:55 INFO - blobuploader == 1.2.4 10:07:55 INFO - docopt == 0.6.1 10:07:55 INFO - manifestparser == 1.1 10:07:55 INFO - mozInstall == 1.12 10:07:55 INFO - mozcrash == 0.17 10:07:55 INFO - mozdebug == 0.1 10:07:55 INFO - mozdevice == 0.48 10:07:55 INFO - mozfile == 1.2 10:07:55 INFO - mozhttpd == 0.7 10:07:55 INFO - mozinfo == 0.9 10:07:55 INFO - mozleak == 0.1 10:07:55 INFO - mozlog == 3.2 10:07:55 INFO - moznetwork == 0.27 10:07:55 INFO - mozprocess == 0.22 10:07:55 INFO - mozprofile == 0.28 10:07:55 INFO - mozrunner == 6.11 10:07:55 INFO - mozscreenshot == 0.1 10:07:55 INFO - mozsystemmonitor == 0.3 10:07:55 INFO - moztest == 0.7 10:07:55 INFO - mozversion == 1.4 10:07:55 INFO - psutil == 3.1.1 10:07:55 INFO - requests == 1.2.3 10:07:55 INFO - wsgiref == 0.1.2 10:07:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07: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')]} 10:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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')]} 10:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:55 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:55 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 10:07:55 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 10:07:55 INFO - Using env: {'DISPLAY': ':0', 10:07:55 INFO - 'HOME': '/home/cltbld', 10:07:55 INFO - 'LANG': 'en_US.UTF-8', 10:07:55 INFO - 'LANGUAGE': 'en_US:en', 10:07:55 INFO - 'LOGNAME': 'cltbld', 10:07:55 INFO - 'MAIL': '/var/mail/cltbld', 10:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:55 INFO - 'MOZ_NO_REMOTE': '1', 10:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:55 INFO - 'NO_EM_RESTART': '1', 10:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:55 INFO - 'PWD': '/builds/slave/test', 10:07:55 INFO - 'SHELL': '/bin/bash', 10:07:55 INFO - 'SHLVL': '1', 10:07:55 INFO - 'TERM': 'linux', 10:07:55 INFO - 'TMOUT': '86400', 10:07:55 INFO - 'USER': 'cltbld', 10:07:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:55 INFO - '_': '/tools/buildbot/bin/python'} 10:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 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)) 10:07:55 INFO - Cleaning up... 10:07:55 INFO - Return code: 0 10:07:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:07: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')]} 10:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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')]} 10:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:55 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 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:55 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 10:07:55 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 10:07:55 INFO - Using env: {'DISPLAY': ':0', 10:07:55 INFO - 'HOME': '/home/cltbld', 10:07:55 INFO - 'LANG': 'en_US.UTF-8', 10:07:55 INFO - 'LANGUAGE': 'en_US:en', 10:07:55 INFO - 'LOGNAME': 'cltbld', 10:07:55 INFO - 'MAIL': '/var/mail/cltbld', 10:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:55 INFO - 'MOZ_NO_REMOTE': '1', 10:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:55 INFO - 'NO_EM_RESTART': '1', 10:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:55 INFO - 'PWD': '/builds/slave/test', 10:07:55 INFO - 'SHELL': '/bin/bash', 10:07:55 INFO - 'SHLVL': '1', 10:07:55 INFO - 'TERM': 'linux', 10:07:55 INFO - 'TMOUT': '86400', 10:07:55 INFO - 'USER': 'cltbld', 10:07:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:55 INFO - '_': '/tools/buildbot/bin/python'} 10:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:55 INFO - Downloading/unpacking jsonschema 10:07:55 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 10:07:55 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 10:07:55 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 10:07:55 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 10:07:55 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 10:07:55 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 10:07:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:07:57 INFO - Downloading/unpacking functools32 (from jsonschema) 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:57 INFO - Downloading functools32-3.2.3-2.tar.gz 10:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:07:57 INFO - warning: no files found matching '*.txt' 10:07:57 INFO - no previously-included directories found matching 'build' 10:07:57 INFO - no previously-included directories found matching 'dist' 10:07:57 INFO - no previously-included directories found matching '.git*' 10:07:57 INFO - Installing collected packages: jsonschema, functools32 10:07:57 INFO - Running setup.py install for functools32 10:07:57 INFO - warning: no files found matching '*.txt' 10:07:57 INFO - no previously-included directories found matching 'build' 10:07:57 INFO - no previously-included directories found matching 'dist' 10:07:57 INFO - no previously-included directories found matching '.git*' 10:07:57 INFO - Successfully installed jsonschema functools32 10:07:57 INFO - Cleaning up... 10:07:57 INFO - Return code: 0 10:07:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5e8e2c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', '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 10:07:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:07:57 INFO - Using env: {'DISPLAY': ':0', 10:07:57 INFO - 'HOME': '/home/cltbld', 10:07:57 INFO - 'LANG': 'en_US.UTF-8', 10:07:57 INFO - 'LANGUAGE': 'en_US:en', 10:07:57 INFO - 'LOGNAME': 'cltbld', 10:07:57 INFO - 'MAIL': '/var/mail/cltbld', 10:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:57 INFO - 'MOZ_NO_REMOTE': '1', 10:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:57 INFO - 'NO_EM_RESTART': '1', 10:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:57 INFO - 'PWD': '/builds/slave/test', 10:07:57 INFO - 'SHELL': '/bin/bash', 10:07:57 INFO - 'SHLVL': '1', 10:07:57 INFO - 'TERM': 'linux', 10:07:57 INFO - 'TMOUT': '86400', 10:07:57 INFO - 'USER': 'cltbld', 10:07:57 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:57 INFO - '_': '/tools/buildbot/bin/python'} 10:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:58 INFO - Downloading/unpacking flake8 10:07:58 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 10:07:58 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 10:07:58 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 10:07:58 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 10:07:58 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 10:07:58 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 10:07:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:08:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:08:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08: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 10:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:00 INFO - no previously-included directories found matching 'docs/_build' 10:08:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:08:00 INFO - Running setup.py install for pep8 10:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:00 INFO - no previously-included directories found matching 'docs/_build' 10:08:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:08:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:08:00 INFO - Cleaning up... 10:08:00 INFO - Return code: 0 10:08:00 INFO - Running post-action listener: _resource_record_post_action 10:08:00 INFO - Running post-action listener: _start_resource_monitoring 10:08:00 INFO - Starting resource monitoring. 10:08:00 INFO - ##### 10:08:00 INFO - ##### Finished create-virtualenv step (success) 10:08:00 INFO - ##### 10:08:00 INFO - ##### 10:08:00 INFO - ##### Running install step. 10:08:00 INFO - ##### 10:08:00 INFO - Running pre-action listener: _resource_record_pre_action 10:08:00 INFO - Running main action method: install 10:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:08:01 INFO - Reading from file tmpfile_stdout 10:08:01 INFO - Detecting whether we're running mozinstall >=1.0... 10:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:08:01 INFO - Reading from file tmpfile_stdout 10:08:01 INFO - Output received: 10:08:01 INFO - Usage: mozinstall [options] installer 10:08:01 INFO - Options: 10:08:01 INFO - -h, --help show this help message and exit 10:08:01 INFO - -d DEST, --destination=DEST 10:08:01 INFO - Directory to install application into. [default: 10:08:01 INFO - "/builds/slave/test"] 10:08:01 INFO - --app=APP Application being installed. [default: firefox] 10:08:01 INFO - mkdir: /builds/slave/test/build/application 10:08:01 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'] 10:08:01 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 10:08:17 INFO - Reading from file tmpfile_stdout 10:08:17 INFO - Output received: 10:08:17 INFO - /builds/slave/test/build/application/firefox/firefox 10:08:17 INFO - Running post-action listener: _resource_record_post_action 10:08:17 INFO - ##### 10:08:17 INFO - ##### Finished install step (success) 10:08:17 INFO - ##### 10:08:17 INFO - ##### 10:08:17 INFO - ##### Running run-tests step. 10:08:17 INFO - ##### 10:08:17 INFO - Running pre-action listener: _resource_record_pre_action 10:08:17 INFO - Running main action method: run_tests 10:08:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:08:17 INFO - Python 2.7.3 10:08:17 INFO - Return code: 0 10:08:17 INFO - grabbing minidump binary from tooltool 10:08:17 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')]} 10:08:17 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 0x1d439c0>, '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 0x1d448c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:08:17 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 10:08:17 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 10:08:17 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 10:08:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:08:17 INFO - Return code: 0 10:08:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:08:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:08:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:08:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:08:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:08:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:08:18 INFO - Return code: 0 10:08:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 10:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 10:08:18 INFO - Using env: {'DISPLAY': ':0', 10:08:18 INFO - 'HOME': '/home/cltbld', 10:08:18 INFO - 'LANG': 'en_US.UTF-8', 10:08:18 INFO - 'LANGUAGE': 'en_US:en', 10:08:18 INFO - 'LOGNAME': 'cltbld', 10:08:18 INFO - 'MAIL': '/var/mail/cltbld', 10:08:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:08:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:18 INFO - 'MOZ_NO_REMOTE': '1', 10:08:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:18 INFO - 'NO_EM_RESTART': '1', 10:08:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:18 INFO - 'PWD': '/builds/slave/test', 10:08:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:08:18 INFO - 'SHELL': '/bin/bash', 10:08:18 INFO - 'SHLVL': '1', 10:08:18 INFO - 'TERM': 'linux', 10:08:18 INFO - 'TMOUT': '86400', 10:08:18 INFO - 'USER': 'cltbld', 10:08:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661', 10:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:18 INFO - '_': '/tools/buildbot/bin/python'} 10:08:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 10:08:19 INFO - mozversion application_buildid: 20160517073121 10:08:19 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 10:08:19 INFO - mozversion application_display_name: Nightly 10:08:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:08:19 INFO - mozversion application_name: Firefox 10:08:19 INFO - mozversion application_remotingname: firefox 10:08:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:08:19 INFO - mozversion application_vendor: Mozilla 10:08:19 INFO - mozversion application_version: 49.0a1 10:08:19 INFO - mozversion platform_buildid: 20160517073121 10:08:19 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 10:08:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:08:19 INFO - mozversion platform_version: 49.0a1 10:08:19 INFO - using testdate: 1463504899 10:08:19 INFO - actual date: 1463504899 10:08:19 INFO - starting webserver on 'localhost:59822' 10:08:19 INFO - SUITE-START | Running 2 tests 10:08:19 INFO - TEST-START | dromaeo_css 10:08:19 INFO - operating with platform_type : linux_ 10:08:19 INFO - Initialising browser for dromaeo_css test... 10:08:19 INFO - TEST-INFO | started process 16247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKZMq79/profile http://localhost:59822/getInfo.html) 10:08:23 INFO - PROCESS | 16247 | __metrics Screen width/height:1600/1200 10:08:23 INFO - PROCESS | 16247 | colorDepth:24 10:08:23 INFO - PROCESS | 16247 | Browser inner width/height: 1024/697 10:08:23 INFO - PROCESS | 16247 | __metrics 10:08:24 ERROR - PROCESS | 16247 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:08:24 INFO - TEST-INFO | 16247: exit 0 10:08:24 INFO - Browser initialized. 10:08:24 INFO - Running cycle 1/1 for dromaeo_css test... 10:08:24 INFO - TEST-INFO | started process 16328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKZMq79/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:36 INFO - PROCESS | 16328 | RSS: Main: 152698880 10:08:36 INFO - PROCESS | 16328 | 10:09:27 INFO - PROCESS | 16328 | 1463504967976 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] 10:11:35 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59822/tests/dromaeo/cssquery-ext.html) 10:11:36 INFO - PROCESS | 16328 | RSS: Main: 243011584 10:11:36 INFO - PROCESS | 16328 | 10:14:38 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-ext.html (next: http://localhost:59822/tests/dromaeo/cssquery-jquery.html) 10:14:39 INFO - PROCESS | 16328 | RSS: Main: 241283072 10:14:39 INFO - PROCESS | 16328 | 10:17:41 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59822/tests/dromaeo/cssquery-mootools.html) 10:17:41 INFO - PROCESS | 16328 | RSS: Main: 241393664 10:17:41 INFO - PROCESS | 16328 | 10:20:43 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59822/tests/dromaeo/cssquery-prototype.html) 10:20:44 INFO - PROCESS | 16328 | RSS: Main: 245395456 10:20:44 INFO - PROCESS | 16328 | 10:23:47 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59822/tests/dromaeo/cssquery-yui.html) 10:23:47 INFO - PROCESS | 16328 | RSS: Main: 224870400 10:23:47 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | Cycle 1(1): loaded http://localhost:59822/tests/dromaeo/cssquery-yui.html (next: http://localhost:59822/tests/dromaeo/cssquery-dojo.html) 10:26:50 INFO - PROCESS | 16328 | RSS: Main: 264515584 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | __start_tp_report 10:26:50 INFO - PROCESS | 16328 | _x_x_mozilla_page_load 10:26:50 INFO - PROCESS | 16328 | _x_x_mozilla_page_load_details 10:26:50 INFO - PROCESS | 16328 | |i|pagename|runs| 10:26:50 INFO - PROCESS | 16328 | |0;dojo.html;3164.1140480665413;3166.176794033552;3193.9938919240444;3204.8077115373067;3236.3689080629274;5278.392984806748;5314.282571852798;5343.759530821113;5376.973115134424;5384.246205531229;5325.707086110262;5328.360596728398;5343.973280133604;5428.782848686058;5436.782528698847;4767.1657459944545;4785.736784476852;4876.439209490918;4977.47736487669;4994.450610432849;4953.529414705597;5018.899622007558;5020.899582008357;5032.370953630796;5036.395632524102;4755.667103302771;4777.163996300651;4856.61147108234;4901.460839307673;4916.237983112605;4859.1496488114435;4863.465018847924;4863.902721945577;4872.293517439952;4976.104301225778;4865.099956508022;4899.8775030624165;4928.433230178503;4929.65492415531;4941.308216849644;5209.47905209476;5260.631755777097;5308.734563271821;5312.840614781563;5351.795845934673;5740.253767010304;5806.2451881255165;5837.09525023622;5850.795222167204;5856.267966504187;5833.416658334175;5847.8245652630485;5851.473367396912;5877.647341159544;5905.173275741399;5321.281626980347;5403.972980135113;5452.100403433429;5456.590755693379;5516.227728118067;5963.522918166536;5978.940210597925;6009;6022.307434644984;6033.095035744674;5568.49883510486;5745.109508026263;5791.3339965903615;5794.768209271666;5799.072148456226;5708.857278567986;5729.169270455844;5737.110747834093;5738.913916291259;5809.854753631109;5697.287839020122;5700.4869561738815;5704.3154821421695;5707.571932105108;5709.600327977085;5644;5673.631213971079;5770.826875193751;5785.334686511021;5785.88428231433;4371.125774845043;4372.650187984954;4395;4432.734035957788;4435;3887.669548088444;3895.902602434962;3897.4738410314244;3913.6477717005037;3959.148783011609;5281.12861377876;5318.095923692904;5365;5375.731213439312;5470.56235501159;5759.1937128802;5762.0204565223175;5777.306723193244;5811.535077193899;5827.533797296206;5480.862978425492;5501.312335958005;5503.147012213113;5503.807366742145;5504.889902201933;4869.805207791649;4893;4916.827911023097;5000.399952005782;5052.191649336162;6017.729202185825;6020.608660437058;6025;6025.66858822769;6040.577159598874;5767.221425107557;5771.97114014444;5775.740091695968;5777.24895763548;5797.797077102281;5811.680357580373;5925.140854576149;5951.91072133927;5979.312379076462;5984.311804142405;5662.8301150965535;5676.858078548069;5676.9432305678065;5679.574031947538;5688.345840228427;5711.800086997101;5781.248437703071;5783.826485205451;5784.392638773036;5786.450287222707;5403.675779453245;5478.233047373291;5514.338279406497;5543.667379957215;5577.44225577439;5544.833654990357;5556.916646250229;5558.221848941233;5560.388357280777;5560.971220324253;5402.378726446509;5420.566354691694;5491.176323551499;5498.972505137449;5539.224508568885;5572.749226284908;5584;5628.1557766333735;5664.631798933056;5692.544596432358;5508.091164957737;5511.338639363302;5514.751836167462;5521.834344969658;5560.165975103767 10:26:50 INFO - PROCESS | 16328 | |1;ext.html;629.3391938464613;637.4900079936053;653.4217217762279;662.6024385368777;666.2704338749071;31574;43655.78172109139;45056.09887802242;45148.32277515832;47871.521284787144;46127.30809037867;46144.30783538241;46672;46731.766341168244;46742.532574674246;9002.459852408843;9134;9142.862857057149;9144;9147.17675409213;8732.388732788708;8901.109889011079;8903.332250081237;8913.1086891311;8916.554172291391;8964.820703585958;9007.909920900773;9019.143181397789;9021.684241051564;9027;8925.910740892572;8956.955215223947;8981.101889811032;8985.685501007467;8989.775255618597;8837.204651581324;8933.59798809055;8935.285177185875;8938.86591701125;8938.955305223431;42442.57557424448;42857;42860;42865.14269714619;42900.78549607263;3279.7868138571157;3397.8640854365794;3447.9482807757904;3461.3769521486124;3466.930661386758;32647;33127.83436082828;33567.66432335694;33730.1567460813;34059.659403405894;3948.1314110895555;3979.621935916083;4142.316517774563;4185.665146788249;4278.165757677254;357991;358862.4966877819;359216;360391.1882643551;360495;55061.449385505635;63958;64532.12807231581;64585.677071614344;64659.67670161619;65434;67488;67601.66199168973;72669.6366518164;72865;15843.287052082684;15870.68258634844;15923;15931.761023584655;15940.601484962737;10412.479376031168;12014.459349329127;12353.691157720948;12431.751364972832;12483.750324993449;19605.901970490057;20327.796722032886;20473.283435079702;20521;20540.794592054186;6939.271376505394;7069.469789765788;7124;7176.282371762886;7229.783106506813;1003.8544411060356;1074.1782536359692;1075.2741899217997;1090.5092708281147;1121.9719507012226;33862.32275354479;33949;34063.489047664305;34167.48748768771;34251.657483424846;1142.6115120858947;1171.3264872698232;1180.415694231361;1206.2943178240828;1252.2236213547658;17286.481405557854;17935.37226197077;17993.100344982697;18071.186796594186;18080.36718714865;498129;499624;500282;500841;501060;59938.70030649819;61537.69231153816;61546.38453615496;61560;61728;1283.8074288856747;1392.8816050635608;1401.2853444743255;1408.1762169131173;1409.239010934084;33108;35025.29949401048;35100.82449587736;35353.469697955064;35358.46962295516;51910;52351;52451;52490.47509525008;52659.47340526699;388433;390585;391014;391459;391997;23897;28782.280442989093;28854.134375968755;30468.84765576158;30784.846075769477;50033.49966500434;50312.245316321016;50358;51215.743921280155;51355.22967155421;41424;42191.1561768769;42484.78757606192;43054.35418468788;43407.78296108625;41167;42132.78933605435;42269;42477.78761106298;42799.3580096305;42437.1512569753;42771;43602.34596481119;43671.34492982544;43738;35699.82150089233;37188;37298.440523391626;37667.43498847574;38301.4254786184;29007.564886526296;29211;29276;29456.852715736284;29467.263318417212 10:26:50 INFO - PROCESS | 16328 | |2;jquery.html;403.8122273142988;404.6196575219292;418.15949940619356;421.41844254928196;421.4647397804788;14116.717665646682;17903.64192716145;19702.11340489678;19727.90136049321;20023;15148.318325675371;18772.061396930134;18836.811631883676;19252.22991080355;20050;5592.86017849554;5623.522000629951;5651.039323315046;5653.830385088433;5782.566307526931;5598.4961353478175;5677.57418193635;5702.857428564298;5747.482726554609;5748.597598168109;4106.3635136553885;4347.478302603676;5933.258342707161;6228.781992630259;6241.531885108612;6225.844353891143;6299.716512756937;6323.20959880015;6340.619562826222;6342.778002769904;5455.754491047913;5562.249096371978;5578.721063946787;5602.243697100921;5604.1593760935775;23408.18071367511;23420.53158936829;23571.76428235713;23861.16485923002;24109.276721698378;2277.555876604063;2280.9771902281095;2305;2463.3964678653842;2883.889702464566;9793.265505087147;10036.24728145392;10417.9479102604;13917.512887048904;15300.234988250542;1959.12818795636;1979.9208031678716;2032.867863588862;2039.775624681284;2054.3939537836227;68839.31160688378;76137.23862761357;82052;83372.99999999939;84277;11330;11421.543138274543;11487.42562871853;11503.884961150448;11726.824097638371;15933.203339832962;15938.681226375407;16045.919770401306;16059.036457812568;16083.195840208176;7611.162772095176;7655.387568994467;7687;8001.279884810278;8003.5197888126895;7164.426845852319;7164.892526612105;7210.170830354471;7286.089238845145;7321.890171647429;4476.485204201558;4816.518348165156;4907.7300748458465;4982.07831551157;5030.748462576857;1850.3616252392903;1884.2180495094383;2036.4705176653881;2096.5806838632334;2115.7672656007826;921.6589861751195;1004.8995100490039;1013.6097602422973;1028;1078.1428764132534;19104.705905723113;19295.710564341545;19298;19471.707924381146;19762.20951161966;1009.9192064634811;1018.495844556949;1024.51335615582;1040.1418829465722;1047.0890325416783;10781.407022613676;11069.335839849635;11154.944225278821;11184.993350598485;11195.944020279847;360166;360597;362268;462887;475136;98435.50782246186;106783.46608267064;107347.46326268319;107940;108397;1076.6769969009265;1112.7607564373861;1114.9052330551995;1124.2130508643982;1125.2798209146154;16339.591510211865;16388.098654574114;16457.91771041185;16698;17256.741148882527;42359;42666;42952.57047429486;43007.569924301606;43266.783666081465;57990.710046449494;58076.4192358071;59607.403925960185;59955.400445996725;60160;8383.622736976973;8503;8562.058173600773;8891.599878005376;8934.508602026692;12125.939370303093;13704.588862334145;13787.724245514833;13815.930920345334;13929;12009.579664711693;12188.878111219128;12190.268583884996;12312.75374492523;12402.379881006094;11333.773324533628;11754;12283.815742763687;12597.74804503923;12602.180858244184;12826.871731282941;12831;12884.48462061507;12991.220526768424;13122.540711075066;8955.462672239686;9146.95426522863;9167.404118732262;9229.49237791928;9471.289653275893;9055.36750004952;12491.312977786309;12676.746465070832;12693.111482196158;12787.296698681661 10:26:50 INFO - PROCESS | 16328 | |3;mootools.html;1385.5081446086645;1489.047009913655;1531.7166324230018;1674.4892807693648;1844.8616353773461;7794.610269486531;8068.475549089312;8078.313343365814;8249.29880960119;8269.669213231464;8347;8511.10633383495;8518.23335899769;8518.659253629847;8521.488710677364;2310.376198426428;2329.95152181519;2330.2077293720217;2331.8834058297016;2332.148765700524;2299.9195028174017;2323.175272778165;2324.3491822289784;2326.9185578504757;2330.067972810872;2299.1722979727283;2302.4474126209757;2323.163661082009;2330.0446816805115;2330.9533809323802;2190.7371115466244;2231.933042008742;2239.2498512998163;2249.0778780699916;2249.55008998201;2190.167736260211;2202.4383782135615;2207.9006444710026;2208.182972300243;2208.447888027993;8401.747947561584;8412;8456.365772567082;8488.787780305542;8775.297976161892;5104.106781313255;5225.634205605609;5227.085260079471;5229.869253268699;5231.869203269911;3398.4222682144095;3430.365382404263;3514.2268700885766;3517.8944631661093;3526.6473352664784;1064.4252103863905;1082.44795154471;1124.7806677697852;1130.9830352544636;1140.7775483780665;12270.693232669166;12386.009119270528;12403.503859845596;12422.06834487408;12427.502899883993;4340.457442819647;4393.956060439355;4422.049259409178;4429.180601588717;4532.116237333754;9740.17208537268;9827.606895724091;9828.754281142885;9831.213502919749;9848.409095454152;5179.637425380262;5214.60890433219;5233.979374022106;5246.685198888079;5247.396549396868;4016.7188296819527;4761.642876784255;4900.534449227318;4921.557059864629;4931.605471562267;3629.6551827576336;3664.6152154023976;3676.724245681531;3701.0932321580844;3745.737798354089;1113.8774734779167;1137.3289759042038;1144.6794897428733;1149.4769879704713;1159.5187996981326;607.8601921558067;935.7988032573029;945.1257586732245;985.7584891701435;995.049727510226;5614.29821272341;5817.156512305776;5858.179854820329;6121.602095863755;6207.348228435949;971.50453268832;987.8073775613686;988.5304480371766;1021.4637315409469;1023.8720159980003;3917.4123881418013;3932.6460618543897;3969.702272329529;4102.794860257035;4220.704550681484;10855.131589472823;11119.27724697892;11164.665060048212;11207.215494915441;11234.49444774987;10933.617323393642;11035.72410689723;11041.33751974868;11045.613403530839;11050.613228536962;8181.877271840927;8212.95791404357;8275.213854683796;8488.957555212184;8506.106858779738;5800.941990580209;5898.056310990221;5900.291964964232;5913.58604897653;6032.728527216198;10158.847617285599;10312.329698569569;10320.741981450525;10334.121599664113;10362.067413932782;9972.451515166735;10425.374477531372;10529.894701052892;10568.947155264175;10580.576776929145;6341.365863413622;6822.181338239443;6889.965550172217;7030.543014704028;7039.683214255473;8304.833903321778;8341.833163336578;8354.958225209079;8395.454295470774;8421.410501265098;7638.197989211053;7733.961330193313;7804.141544430222;7810.336121429515;7979.48133371329;7040.401565866959;7216.530925489827;7304.707811687473;7320.780376588711;7466.104067511933;6864.622445765531;6908.48186386013;6942.930570694229;6944.166699996033;6957;5096.541311282002;5171.612129090408;5200.6879587224885;5263.6841789492755;5310.840674779763;5176.767045482887;5265.3944796348915;5280.577553795764;5290.576753859759;5372.785088596413 10:26:50 INFO - PROCESS | 16328 | |4;prototype.html;317.96819321302377;327.22121351184177;328.942435073862;341.57644520794224;342.53757427472897;5280.491766410953;5303.602229832759;5305.442928492511;5313.362396512419;5313.548348390382;5182.533571978521;5223.686578805275;5259;5269.7603518504575;5274.129768588178;3869.7484663496925;4003.939940900889;4119.546849846514;4135.32519329672;4170.582941705836;4090.754554726711;4111.917761644765;4130.256553820326;4148.875533733978;4162.521310049353;3892.299386110499;4064.4512990746166;4085.8569950051756;4098.221337945795;4102.446169767088;3995.480587523618;4073.7555746655;4089.5092588889224;4097.344424892003;4098.897527561805;3622.728295377831;3623.673869351771;3625.8368373422995;3634.60019397866;3653.5798383186007;4999.775145031471;5025.899482010376;5042.747862606855;5047.722375269359;5053.267276244961;4562.77186140695;4575.428071491064;4684.976575117103;4692.671512994092;4717.952820471785;5363.973180134113;5403.244345383843;5411;5430.506610682054;5434;3973.8807835764974;3982.7411218270718;3988.4017397390335;3990.4213888986224;3991.840326386941;5315.495027972336;5643.379228284885;5665.773369065232;5693.914591281134;5701.375108094173;3972.841086356513;3982.422548730418;3985.6811455083525;4011.197760447922;4012.29784787661;4030.7380020299174;4033.6285662874175;4035.5157381114454;4049.7570145791346;4077.857274995361;4470.742275151093;4628.3983082199775;4631.610516844933;4640.535946405333;4688.640294314679;5478.534324582375;5482.91775623366;5491;5499.945000549943;5507.614466987273;1394.3237529798039;1400.8949328800177;1403.4596680278166;1406.0790182430524;1411.1110000699493;882.5322579033123;891.5809569502453;950.5152372290044;969.3602222533235;973.4986481961795;5193.85735138269;5205.115130427836;5220;5253.684778913277;5262.947370526245;5501.559875209973;5509.162781167702;5555;5556.666600004012;5566.749496272678;5125.487451254845;5257.185136303879;5260.210968354778;5264.684118952875;5291.285676433632;2939.8383088929886;2944.8380339081127;2956.0392872316584;2959.8224106553675;2966.46603611352;5506.788506528557;5727.3699893011735;5728.885422291531;5753.769849205984;5795.362510123883;5609.80365687199;5612.747426365823;5624.6625202487985;5641.576881733887;5651.152327150878;5709.886572118397;5717.714114294268;5736.823951090017;5736.971315143398;5755.62588431759;5332.840014799563;5545.639533430314;5595.496405323541;5713.628614140068;5720;5594.972025140011;5596.552275818006;5612.635178713371;5626.38109807929;5634.295713035871;5736.627119237236;5852.209951656472;5857.8535536610225;5929.703514824328;5960.314563825215;4033.132876431464;4038.1721747042375;4053.756774593534;4069.491313585802;4080.489938757655;5642.322921249476;5644.915326270191;5692.686902220417;5710.743016564182;5726.914096288642;5324.494173053554;5499.807506737245;5516.14499752539;5578.191162281365;5613.46672066153;5407.83776486706;5523.254360661259;5548.445155484419;5552.333719953632;5559.916601250903;5232.7645255964335;5307.203919412119;5404.513593776579;5452.482014208644;5491.258680078138;5530.391656917816;5533.5849811263515;5539.224508568885;5572.637778544382;5576.302962129734;5439.211314359475;5445.210444485607;5469.781208751605;5479.506844384025;5511.83069734896 10:26:50 INFO - PROCESS | 16328 | |5;yui.html;742.7548908860076;815.2296080204208;842.0568962761706;854.7948492361832;882.7484167012399;15674.059556426608;16295.674086518293;16338;16355.75466368004;16396.180190990446;13814.1020833646;13854.445822167101;13865;13870.4451821927;13895.444182232699;659.8284446044015;688.6625553478784;776.0725932510652;809.6478032056045;812.7358608452247;798.1061211443163;803.3533005930253;805.2269820971845;810.5784991804259;811.2050190813006;864.0711235421946;867.5055218525443;868.4224990381389;868.8653258744905;869.2003356911625;703.9260877607854;816.0411516468156;859.1623167411786;859.8925134358205;860.6600392844791;626.4894111299277;627.2598333965918;627.5136769004013;627.5293529852611;628.1927722876128;14918.17950012749;14941.925290373587;14947.878909081754;14994.850051499454;14998.925005375013;13631.795523067161;13842.377093030736;13890.23603701796;13901.443942242298;13961.441542338396;11827.408629568487;11847.526098956117;12026.338551379671;12111.091668124925;12427.565035223724;4503.774811259456;4566.13243483737;4622.745748983804;4687.812487500496;4694.0611877624615;61076.69461652708;61604;62243.68878155626;62900;62993;10308.948455257676;10425.895741042645;10569.471526423802;10599.788004240027;10600.681979540626;18645.254189832256;18781.15484803187;19172.808271917103;19297.807021929602;19306.4208073758;8496.66013359471;8506.149385061444;8615;8623.180797824196;8714.259287960467;4643.953560464352;4749.097671442415;4766.6901651392545;4801.4958429364415;4854.150523658416;3413.232022794851;3465.4282043462545;3471.9826400867832;3480.19955410252;3497.108237399447;543.9836804895859;611.4680228201494;621.1304174156218;657.1227411405827;696.0568429777679;614.50839328537;702.9578225306395;709.9148102227663;725.9274072592699;726.9963650181716;9482.668106616235;9735.123838854537;9755.463449510346;9765.951170244103;9779;673.1955313400538;678.8268991407159;733.7431898835365;765.2615226306641;778.7741554949115;4222.3244280914905;4233.216854881856;4340.60934515895;4376.299792033259;4547.113312903952;73572;74217;77342.6132869332;77466;77642.6117869407;66564.6671766638;67532.3246767526;67763.3223667757;67990.32009679939;68892;60450;60588.39411605916;60677;61355;61594.69202653958;9432;9461.952690236505;9488.620455181715;9495.287853410884;9565.091316324939;39593.208135837696;40026.39960400655;40177.39733903935;40870.79564602158;41339.7933010333;44156.33765493584;45302.773486132355;46134.30798538092;49370.75314623404;56002;11521.711957201138;11589.768204635691;11856.585019524275;11944.880551194725;12188.390580471118;12685;12779.97760179202;12894.419751111034;12916.935415322863;12989.28558929268;9785.412875227508;12328.691782705504;13468.46126154982;13777.655558611115;14104.717905642035;12563.811542826683;12856.164349317265;13842.446302148204;13902.304884755924;14272.500462483764;12775.80836287475;12870.356482176041;13277.203367797963;13438.731225375634;13536.187828730308;10795.40625265618;11170.832437513282;11306.152038596974;11643.417829108681;11706.180567360203;11053.447327633747;11087;11100.333979961228;11199.10407167441;11209.439528023404 10:26:50 INFO - PROCESS | 16328 | __end_tp_report 10:26:50 INFO - PROCESS | 16328 | __start_cc_report 10:26:50 INFO - PROCESS | 16328 | _x_x_mozilla_cycle_collect,799 10:26:50 INFO - PROCESS | 16328 | __end_cc_report 10:26:50 INFO - PROCESS | 16328 | __startTimestamp1463506010588__endTimestamp 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | ------- Summary: start ------- 10:26:50 INFO - PROCESS | 16328 | Number of tests: 6 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#0] dojo.html Cycles:165 Average:5349.41 Median:5514.34 stddev:605.16 (11.0%) stddev-sans-first:582.23 10:26:50 INFO - PROCESS | 16328 | Values: 3164.1 3166.2 3194.0 3204.8 3236.4 5278.4 5314.3 5343.8 5377.0 5384.2 5325.7 5328.4 5344.0 5428.8 5436.8 4767.2 4785.7 4876.4 4977.5 4994.5 4953.5 5018.9 5020.9 5032.4 5036.4 4755.7 4777.2 4856.6 4901.5 4916.2 4859.1 4863.5 4863.9 4872.3 4976.1 4865.1 4899.9 4928.4 4929.7 4941.3 5209.5 5260.6 5308.7 5312.8 5351.8 5740.3 5806.2 5837.1 5850.8 5856.3 5833.4 5847.8 5851.5 5877.6 5905.2 5321.3 5404.0 5452.1 5456.6 5516.2 5963.5 5978.9 6009.0 6022.3 6033.1 5568.5 5745.1 5791.3 5794.8 5799.1 5708.9 5729.2 5737.1 5738.9 5809.9 5697.3 5700.5 5704.3 5707.6 5709.6 5644.0 5673.6 5770.8 5785.3 5785.9 4371.1 4372.7 4395.0 4432.7 4435.0 3887.7 3895.9 3897.5 3913.6 3959.1 5281.1 5318.1 5365.0 5375.7 5470.6 5759.2 5762.0 5777.3 5811.5 5827.5 5480.9 5501.3 5503.1 5503.8 5504.9 4869.8 4893.0 4916.8 5000.4 5052.2 6017.7 6020.6 6025.0 6025.7 6040.6 5767.2 5772.0 5775.7 5777.2 5797.8 5811.7 5925.1 5951.9 5979.3 5984.3 5662.8 5676.9 5676.9 5679.6 5688.3 5711.8 5781.2 5783.8 5784.4 5786.5 5403.7 5478.2 5514.3 5543.7 5577.4 5544.8 5556.9 5558.2 5560.4 5561.0 5402.4 5420.6 5491.2 5499.0 5539.2 5572.7 5584.0 5628.2 5664.6 5692.5 5508.1 5511.3 5514.8 5521.8 5560.2 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#1] ext.html Cycles:180 Average:59254.02 Median:42439.86 stddev:111197.05 (262.0%) stddev-sans-first:111421.87 10:26:50 INFO - PROCESS | 16328 | Values: 629.3 637.5 653.4 662.6 666.3 31574.0 43655.8 45056.1 45148.3 47871.5 46127.3 46144.3 46672.0 46731.8 46742.5 9002.5 9134.0 9142.9 9144.0 9147.2 8732.4 8901.1 8903.3 8913.1 8916.6 8964.8 9007.9 9019.1 9021.7 9027.0 8925.9 8957.0 8981.1 8985.7 8989.8 8837.2 8933.6 8935.3 8938.9 8939.0 42442.6 42857.0 42860.0 42865.1 42900.8 3279.8 3397.9 3447.9 3461.4 3466.9 32647.0 33127.8 33567.7 33730.2 34059.7 3948.1 3979.6 4142.3 4185.7 4278.2 357991.0 358862.5 359216.0 360391.2 360495.0 55061.4 63958.0 64532.1 64585.7 64659.7 65434.0 67488.0 67601.7 72669.6 72865.0 15843.3 15870.7 15923.0 15931.8 15940.6 10412.5 12014.5 12353.7 12431.8 12483.8 19605.9 20327.8 20473.3 20521.0 20540.8 6939.3 7069.5 7124.0 7176.3 7229.8 1003.9 1074.2 1075.3 1090.5 1122.0 33862.3 33949.0 34063.5 34167.5 34251.7 1142.6 1171.3 1180.4 1206.3 1252.2 17286.5 17935.4 17993.1 18071.2 18080.4 498129.0 499624.0 500282.0 500841.0 501060.0 59938.7 61537.7 61546.4 61560.0 61728.0 1283.8 1392.9 1401.3 1408.2 1409.2 33108.0 35025.3 35100.8 35353.5 35358.5 51910.0 52351.0 52451.0 52490.5 52659.5 388433.0 390585.0 391014.0 391459.0 391997.0 23897.0 28782.3 28854.1 30468.8 30784.8 50033.5 50312.2 50358.0 51215.7 51355.2 41424.0 42191.2 42484.8 43054.4 43407.8 41167.0 42132.8 42269.0 42477.8 42799.4 42437.2 42771.0 43602.3 43671.3 43738.0 35699.8 37188.0 37298.4 37667.4 38301.4 29007.6 29211.0 29276.0 29456.9 29467.3 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#2] jquery.html Cycles:180 Average:27133.56 Median:2196.66 stddev:68160.72 (3102.9%) stddev-sans-first:68322.38 10:26:50 INFO - PROCESS | 16328 | Values: 403.8 404.6 418.2 421.4 421.5 14116.7 17903.6 19702.1 19727.9 20023.0 15148.3 18772.1 18836.8 19252.2 20050.0 5592.9 5623.5 5651.0 5653.8 5782.6 5598.5 5677.6 5702.9 5747.5 5748.6 4106.4 4347.5 5933.3 6228.8 6241.5 6225.8 6299.7 6323.2 6340.6 6342.8 5455.8 5562.2 5578.7 5602.2 5604.2 23408.2 23420.5 23571.8 23861.2 24109.3 2277.6 2281.0 2305.0 2463.4 2883.9 9793.3 10036.2 10417.9 13917.5 15300.2 1959.1 1979.9 2032.9 2039.8 2054.4 68839.3 76137.2 82052.0 83373.0 84277.0 11330.0 11421.5 11487.4 11503.9 11726.8 15933.2 15938.7 16045.9 16059.0 16083.2 7611.2 7655.4 7687.0 8001.3 8003.5 7164.4 7164.9 7210.2 7286.1 7321.9 4476.5 4816.5 4907.7 4982.1 5030.7 1850.4 1884.2 2036.5 2096.6 2115.8 921.7 1004.9 1013.6 1028.0 1078.1 19104.7 19295.7 19298.0 19471.7 19762.2 1009.9 1018.5 1024.5 1040.1 1047.1 10781.4 11069.3 11154.9 11185.0 11195.9 360166.0 360597.0 362268.0 462887.0 475136.0 98435.5 106783.5 107347.5 107940.0 108397.0 1076.7 1112.8 1114.9 1124.2 1125.3 16339.6 16388.1 16457.9 16698.0 17256.7 42359.0 42666.0 42952.6 43007.6 43266.8 57990.7 58076.4 59607.4 59955.4 60160.0 8383.6 8503.0 8562.1 8891.6 8934.5 12125.9 13704.6 13787.7 13815.9 13929.0 12009.6 12188.9 12190.3 12312.8 12402.4 11333.8 11754.0 12283.8 12597.7 12602.2 12826.9 12831.0 12884.5 12991.2 13122.5 8955.5 9147.0 9167.4 9229.5 9471.3 9055.4 12491.3 12676.7 12693.1 12787.3 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#3] mootools.html Cycles:180 Average:5717.56 Median:5014.07 stddev:3283.55 (65.5%) stddev-sans-first:3276.62 10:26:50 INFO - PROCESS | 16328 | Values: 1385.5 1489.0 1531.7 1674.5 1844.9 7794.6 8068.5 8078.3 8249.3 8269.7 8347.0 8511.1 8518.2 8518.7 8521.5 2310.4 2330.0 2330.2 2331.9 2332.1 2299.9 2323.2 2324.3 2326.9 2330.1 2299.2 2302.4 2323.2 2330.0 2331.0 2190.7 2231.9 2239.2 2249.1 2249.6 2190.2 2202.4 2207.9 2208.2 2208.4 8401.7 8412.0 8456.4 8488.8 8775.3 5104.1 5225.6 5227.1 5229.9 5231.9 3398.4 3430.4 3514.2 3517.9 3526.6 1064.4 1082.4 1124.8 1131.0 1140.8 12270.7 12386.0 12403.5 12422.1 12427.5 4340.5 4394.0 4422.0 4429.2 4532.1 9740.2 9827.6 9828.8 9831.2 9848.4 5179.6 5214.6 5234.0 5246.7 5247.4 4016.7 4761.6 4900.5 4921.6 4931.6 3629.7 3664.6 3676.7 3701.1 3745.7 1113.9 1137.3 1144.7 1149.5 1159.5 607.9 935.8 945.1 985.8 995.0 5614.3 5817.2 5858.2 6121.6 6207.3 971.5 987.8 988.5 1021.5 1023.9 3917.4 3932.6 3969.7 4102.8 4220.7 10855.1 11119.3 11164.7 11207.2 11234.5 10933.6 11035.7 11041.3 11045.6 11050.6 8181.9 8213.0 8275.2 8489.0 8506.1 5800.9 5898.1 5900.3 5913.6 6032.7 10158.8 10312.3 10320.7 10334.1 10362.1 9972.5 10425.4 10529.9 10568.9 10580.6 6341.4 6822.2 6890.0 7030.5 7039.7 8304.8 8341.8 8355.0 8395.5 8421.4 7638.2 7734.0 7804.1 7810.3 7979.5 7040.4 7216.5 7304.7 7320.8 7466.1 6864.6 6908.5 6942.9 6944.2 6957.0 5096.5 5171.6 5200.7 5263.7 5310.8 5176.8 5265.4 5280.6 5290.6 5372.8 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#4] prototype.html Cycles:180 Average:4617.14 Median:5285.89 stddev:1353.43 (25.6%) stddev-sans-first:1318.20 10:26:50 INFO - PROCESS | 16328 | Values: 318.0 327.2 328.9 341.6 342.5 5280.5 5303.6 5305.4 5313.4 5313.5 5182.5 5223.7 5259.0 5269.8 5274.1 3869.7 4003.9 4119.5 4135.3 4170.6 4090.8 4111.9 4130.3 4148.9 4162.5 3892.3 4064.5 4085.9 4098.2 4102.4 3995.5 4073.8 4089.5 4097.3 4098.9 3622.7 3623.7 3625.8 3634.6 3653.6 4999.8 5025.9 5042.7 5047.7 5053.3 4562.8 4575.4 4685.0 4692.7 4718.0 5364.0 5403.2 5411.0 5430.5 5434.0 3973.9 3982.7 3988.4 3990.4 3991.8 5315.5 5643.4 5665.8 5693.9 5701.4 3972.8 3982.4 3985.7 4011.2 4012.3 4030.7 4033.6 4035.5 4049.8 4077.9 4470.7 4628.4 4631.6 4640.5 4688.6 5478.5 5482.9 5491.0 5499.9 5507.6 1394.3 1400.9 1403.5 1406.1 1411.1 882.5 891.6 950.5 969.4 973.5 5193.9 5205.1 5220.0 5253.7 5262.9 5501.6 5509.2 5555.0 5556.7 5566.7 5125.5 5257.2 5260.2 5264.7 5291.3 2939.8 2944.8 2956.0 2959.8 2966.5 5506.8 5727.4 5728.9 5753.8 5795.4 5609.8 5612.7 5624.7 5641.6 5651.2 5709.9 5717.7 5736.8 5737.0 5755.6 5332.8 5545.6 5595.5 5713.6 5720.0 5595.0 5596.6 5612.6 5626.4 5634.3 5736.6 5852.2 5857.9 5929.7 5960.3 4033.1 4038.2 4053.8 4069.5 4080.5 5642.3 5644.9 5692.7 5710.7 5726.9 5324.5 5499.8 5516.1 5578.2 5613.5 5407.8 5523.3 5548.4 5552.3 5559.9 5232.8 5307.2 5404.5 5452.5 5491.3 5530.4 5533.6 5539.2 5572.6 5576.3 5439.2 5445.2 5469.8 5479.5 5511.8 10:26:50 INFO - PROCESS | 16328 | 10:26:50 INFO - PROCESS | 16328 | [#5] yui.html Cycles:180 Average:16504.42 Median:4665.88 stddev:20486.84 (439.1%) stddev-sans-first:20510.12 10:26:50 INFO - PROCESS | 16328 | Values: 742.8 815.2 842.1 854.8 882.7 15674.1 16295.7 16338.0 16355.8 16396.2 13814.1 13854.4 13865.0 13870.4 13895.4 659.8 688.7 776.1 809.6 812.7 798.1 803.4 805.2 810.6 811.2 864.1 867.5 868.4 868.9 869.2 703.9 816.0 859.2 859.9 860.7 626.5 627.3 627.5 627.5 628.2 14918.2 14941.9 14947.9 14994.9 14998.9 13631.8 13842.4 13890.2 13901.4 13961.4 11827.4 11847.5 12026.3 12111.1 12427.6 4503.8 4566.1 4622.7 4687.8 4694.1 61076.7 61604.0 62243.7 62900.0 62993.0 10308.9 10425.9 10569.5 10599.8 10600.7 18645.3 18781.2 19172.8 19297.8 19306.4 8496.7 8506.1 8615.0 8623.2 8714.3 4644.0 4749.1 4766.7 4801.5 4854.2 3413.2 3465.4 3472.0 3480.2 3497.1 544.0 611.5 621.1 657.1 696.1 614.5 703.0 709.9 725.9 727.0 9482.7 9735.1 9755.5 9766.0 9779.0 673.2 678.8 733.7 765.3 778.8 4222.3 4233.2 4340.6 4376.3 4547.1 73572.0 74217.0 77342.6 77466.0 77642.6 66564.7 67532.3 67763.3 67990.3 68892.0 60450.0 60588.4 60677.0 61355.0 61594.7 9432.0 9462.0 9488.6 9495.3 9565.1 39593.2 40026.4 40177.4 40870.8 41339.8 44156.3 45302.8 46134.3 49370.8 56002.0 11521.7 11589.8 11856.6 11944.9 12188.4 12685.0 12780.0 12894.4 12916.9 12989.3 9785.4 12328.7 13468.5 13777.7 14104.7 12563.8 12856.2 13842.4 13902.3 14272.5 12775.8 12870.4 13277.2 13438.7 13536.2 10795.4 11170.8 11306.2 11643.4 11706.2 11053.4 11087.0 11100.3 11199.1 11209.4 10:26:50 INFO - PROCESS | 16328 | -------- Summary: end -------- 10:26:50 INFO - PROCESS | 16328 | 10:26:51 INFO - TEST-INFO | 16328: exit 0 10:26:51 INFO - TEST-OK | dromaeo_css | took 1112300ms 10:26:51 INFO - TEST-START | kraken 10:26:51 INFO - operating with platform_type : linux_ 10:26:51 INFO - Initialising browser for kraken test... 10:26:51 INFO - TEST-INFO | started process 16515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXqUNU/profile http://localhost:59822/getInfo.html) 10:26:55 INFO - PROCESS | 16515 | __metrics Screen width/height:1600/1200 10:26:55 INFO - PROCESS | 16515 | colorDepth:24 10:26:55 INFO - PROCESS | 16515 | Browser inner width/height: 1024/697 10:26:55 INFO - PROCESS | 16515 | __metrics 10:26:56 ERROR - PROCESS | 16515 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:26:56 INFO - TEST-INFO | 16515: exit 0 10:26:56 INFO - Browser initialized. 10:26:56 INFO - Running cycle 1/1 for kraken test... 10:26:56 INFO - TEST-INFO | started process 16599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXqUNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:08 INFO - PROCESS | 16599 | RSS: Main: 152506368 10:27:08 INFO - PROCESS | 16599 | 10:27:11 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59822/tests/kraken/driver.html?testName=audio-beat-detection) 10:27:12 INFO - PROCESS | 16599 | RSS: Main: 208744448 10:27:12 INFO - PROCESS | 16599 | 10:27:14 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59822/tests/kraken/driver.html?testName=audio-dft) 10:27:15 INFO - PROCESS | 16599 | RSS: Main: 196673536 10:27:15 INFO - PROCESS | 16599 | 10:27:18 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59822/tests/kraken/driver.html?testName=audio-fft) 10:27:19 INFO - PROCESS | 16599 | RSS: Main: 265854976 10:27:19 INFO - PROCESS | 16599 | 10:27:21 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59822/tests/kraken/driver.html?testName=audio-oscillator) 10:27:22 INFO - PROCESS | 16599 | RSS: Main: 173850624 10:27:22 INFO - PROCESS | 16599 | 10:27:24 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59822/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:27:24 INFO - PROCESS | 16599 | RSS: Main: 179466240 10:27:24 INFO - PROCESS | 16599 | 10:27:28 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59822/tests/kraken/driver.html?testName=imaging-darkroom) 10:27:28 INFO - PROCESS | 16599 | RSS: Main: 214855680 10:27:28 INFO - PROCESS | 16599 | 10:27:32 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59822/tests/kraken/driver.html?testName=imaging-desaturate) 10:27:32 INFO - PROCESS | 16599 | RSS: Main: 213016576 10:27:32 INFO - PROCESS | 16599 | 10:27:35 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59822/tests/kraken/driver.html?testName=json-parse-financial) 10:27:36 INFO - PROCESS | 16599 | RSS: Main: 214024192 10:27:36 INFO - PROCESS | 16599 | 10:27:37 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59822/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:27:38 INFO - PROCESS | 16599 | RSS: Main: 204091392 10:27:38 INFO - PROCESS | 16599 | 10:27:40 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:27:41 INFO - PROCESS | 16599 | RSS: Main: 209174528 10:27:41 INFO - PROCESS | 16599 | 10:27:43 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:27:43 INFO - PROCESS | 16599 | RSS: Main: 217686016 10:27:43 INFO - PROCESS | 16599 | 10:27:46 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:27:46 INFO - PROCESS | 16599 | RSS: Main: 223993856 10:27:46 INFO - PROCESS | 16599 | 10:27:49 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:27:50 INFO - PROCESS | 16599 | RSS: Main: 220008448 10:27:50 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | Cycle 1(1): loaded http://localhost:59822/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59822/tests/kraken/driver.html?testName=ai-astar) 10:27:52 INFO - PROCESS | 16599 | RSS: Main: 264994816 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | __start_tp_report 10:27:52 INFO - PROCESS | 16599 | _x_x_mozilla_page_load 10:27:52 INFO - PROCESS | 16599 | _x_x_mozilla_page_load_details 10:27:52 INFO - PROCESS | 16599 | |i|pagename|runs| 10:27:52 INFO - PROCESS | 16599 | |0;ai-astar;109;105;109;108;109;109;109;110;109;108 10:27:52 INFO - PROCESS | 16599 | |1;audio-beat-detection;169;124;147;215;123;150;151;131;182;123 10:27:52 INFO - PROCESS | 16599 | |2;audio-dft;161;159;159;162;160;160;161;160;160;161 10:27:52 INFO - PROCESS | 16599 | |3;audio-fft;74;73;75;153;76;75;76;74;139;75 10:27:52 INFO - PROCESS | 16599 | |4;audio-oscillator;90;91;146;92;90;91;127;91;91;119 10:27:52 INFO - PROCESS | 16599 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;116;116;117 10:27:52 INFO - PROCESS | 16599 | |6;imaging-darkroom;145;146;145;145;145;146;145;145;145;146 10:27:52 INFO - PROCESS | 16599 | |7;imaging-desaturate;94;95;94;95;95;95;96;95;95;94 10:27:52 INFO - PROCESS | 16599 | |8;json-parse-financial;55;53;55;53;55;54;56;106;52;52 10:27:52 INFO - PROCESS | 16599 | |9;json-stringify-tinderbox;60;59;59;59;60;59;60;59;59;59 10:27:52 INFO - PROCESS | 16599 | |10;stanford-crypto-aes;77;78;78;77;79;79;79;79;78;79 10:27:52 INFO - PROCESS | 16599 | |11;stanford-crypto-ccm;124;121;122;119;127;117;120;117;190;127 10:27:52 INFO - PROCESS | 16599 | |12;stanford-crypto-pbkdf2;159;160;162;159;161;159;159;160;160;158 10:27:52 INFO - PROCESS | 16599 | |13;stanford-crypto-sha256-iterative;62;61;62;63;62;63;62;66;61;62 10:27:52 INFO - PROCESS | 16599 | __end_tp_report 10:27:52 INFO - PROCESS | 16599 | __start_cc_report 10:27:52 INFO - PROCESS | 16599 | _x_x_mozilla_cycle_collect,1868 10:27:52 INFO - PROCESS | 16599 | __end_cc_report 10:27:52 INFO - PROCESS | 16599 | __startTimestamp1463506072334__endTimestamp 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | ------- Summary: start ------- 10:27:52 INFO - PROCESS | 16599 | Number of tests: 14 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:1.35 (1.2%) stddev-sans-first:1.42 10:27:52 INFO - PROCESS | 16599 | Values: 109.0 105.0 109.0 108.0 109.0 109.0 109.0 110.0 109.0 108.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#1] audio-beat-detection Cycles:10 Average:151.50 Median:150.50 stddev:29.99 (19.9%) stddev-sans-first:31.13 10:27:52 INFO - PROCESS | 16599 | Values: 169.0 124.0 147.0 215.0 123.0 150.0 151.0 131.0 182.0 123.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 10:27:52 INFO - PROCESS | 16599 | Values: 161.0 159.0 159.0 162.0 160.0 160.0 161.0 160.0 160.0 161.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#3] audio-fft Cycles:10 Average:89.00 Median:75.00 stddev:30.24 (40.3%) stddev-sans-first:31.58 10:27:52 INFO - PROCESS | 16599 | Values: 74.0 73.0 75.0 153.0 76.0 75.0 76.0 74.0 139.0 75.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:91.00 stddev:20.32 (22.3%) stddev-sans-first:21.02 10:27:52 INFO - PROCESS | 16599 | Values: 90.0 91.0 146.0 92.0 90.0 91.0 127.0 91.0 91.0 119.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:27:52 INFO - PROCESS | 16599 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 116.0 117.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 10:27:52 INFO - PROCESS | 16599 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 10:27:52 INFO - PROCESS | 16599 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:54.50 stddev:16.54 (30.3%) stddev-sans-first:17.47 10:27:52 INFO - PROCESS | 16599 | Values: 55.0 53.0 55.0 53.0 55.0 54.0 56.0 106.0 52.0 52.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 10:27:52 INFO - PROCESS | 16599 | Values: 60.0 59.0 59.0 59.0 60.0 59.0 60.0 59.0 59.0 59.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.73 10:27:52 INFO - PROCESS | 16599 | Values: 77.0 78.0 78.0 77.0 79.0 79.0 79.0 79.0 78.0 79.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:123.00 stddev:21.94 (17.8%) stddev-sans-first:23.21 10:27:52 INFO - PROCESS | 16599 | Values: 124.0 121.0 122.0 119.0 127.0 117.0 120.0 117.0 190.0 127.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 10:27:52 INFO - PROCESS | 16599 | Values: 159.0 160.0 162.0 159.0 161.0 159.0 159.0 160.0 160.0 158.0 10:27:52 INFO - PROCESS | 16599 | 10:27:52 INFO - PROCESS | 16599 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.00 stddev:1.43 (2.3%) stddev-sans-first:1.51 10:27:52 INFO - PROCESS | 16599 | Values: 62.0 61.0 62.0 63.0 62.0 63.0 62.0 66.0 61.0 62.0 10:27:52 INFO - PROCESS | 16599 | -------- Summary: end -------- 10:27:52 INFO - PROCESS | 16599 | 10:27:53 INFO - TEST-INFO | 16599: exit 0 10:27:53 INFO - TEST-OK | kraken | took 61944ms 10:27:53 INFO - SUITE-END | took 1174s 10:27:53 INFO - Completed test suite (00:19:34) 10:27:53 INFO - javascript benchmark 10:27:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3164.1140480665413, 3166.176794033552, 3193.9938919240444, 3204.8077115373067, 3236.3689080629274, 5278.392984806748, 5314.282571852798, 5343.759530821113, 5376.973115134424, 5384.246205531229, 5325.707086110262, 5328.360596728398, 5343.973280133604, 5428.782848686058, 5436.782528698847, 4767.1657459944545, 4785.736784476852, 4876.439209490918, 4977.47736487669, 4994.450610432849, 4953.529414705597, 5018.899622007558, 5020.899582008357, 5032.370953630796, 5036.395632524102, 4755.667103302771, 4777.163996300651, 4856.61147108234, 4901.460839307673, 4916.237983112605, 4859.1496488114435, 4863.465018847924, 4863.902721945577, 4872.293517439952, 4976.104301225778, 4865.099956508022, 4899.8775030624165, 4928.433230178503, 4929.65492415531, 4941.308216849644, 5209.47905209476, 5260.631755777097, 5308.734563271821, 5312.840614781563, 5351.795845934673, 5740.253767010304, 5806.2451881255165, 5837.09525023622, 5850.795222167204, 5856.267966504187, 5833.416658334175, 5847.8245652630485, 5851.473367396912, 5877.647341159544, 5905.173275741399, 5321.281626980347, 5403.972980135113, 5452.100403433429, 5456.590755693379, 5516.227728118067, 5963.522918166536, 5978.940210597925, 6009.0, 6022.307434644984, 6033.095035744674, 5568.49883510486, 5745.109508026263, 5791.3339965903615, 5794.768209271666, 5799.072148456226, 5708.857278567986, 5729.169270455844, 5737.110747834093, 5738.913916291259, 5809.854753631109, 5697.287839020122, 5700.4869561738815, 5704.3154821421695, 5707.571932105108, 5709.600327977085, 5644.0, 5673.631213971079, 5770.826875193751, 5785.334686511021, 5785.88428231433, 4371.125774845043, 4372.650187984954, 4395.0, 4432.734035957788, 4435.0, 3887.669548088444, 3895.902602434962, 3897.4738410314244, 3913.6477717005037, 3959.148783011609, 5281.12861377876, 5318.095923692904, 5365.0, 5375.731213439312, 5470.56235501159, 5759.1937128802, 5762.0204565223175, 5777.306723193244, 5811.535077193899, 5827.533797296206, 5480.862978425492, 5501.312335958005, 5503.147012213113, 5503.807366742145, 5504.889902201933, 4869.805207791649, 4893.0, 4916.827911023097, 5000.399952005782, 5052.191649336162, 6017.729202185825, 6020.608660437058, 6025.0, 6025.66858822769, 6040.577159598874, 5767.221425107557, 5771.97114014444, 5775.740091695968, 5777.24895763548, 5797.797077102281, 5811.680357580373, 5925.140854576149, 5951.91072133927, 5979.312379076462, 5984.311804142405, 5662.8301150965535, 5676.858078548069, 5676.9432305678065, 5679.574031947538, 5688.345840228427, 5711.800086997101, 5781.248437703071, 5783.826485205451, 5784.392638773036, 5786.450287222707, 5403.675779453245, 5478.233047373291, 5514.338279406497, 5543.667379957215, 5577.44225577439, 5544.833654990357, 5556.916646250229, 5558.221848941233, 5560.388357280777, 5560.971220324253, 5402.378726446509, 5420.566354691694, 5491.176323551499, 5498.972505137449, 5539.224508568885, 5572.749226284908, 5584.0, 5628.1557766333735, 5664.631798933056, 5692.544596432358, 5508.091164957737, 5511.338639363302, 5514.751836167462, 5521.834344969658, 5560.165975103767], "value": 5309.260175237828, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.3391938464613, 637.4900079936053, 653.4217217762279, 662.6024385368777, 666.2704338749071, 31574.0, 43655.78172109139, 45056.09887802242, 45148.32277515832, 47871.521284787144, 46127.30809037867, 46144.30783538241, 46672.0, 46731.766341168244, 46742.532574674246, 9002.459852408843, 9134.0, 9142.862857057149, 9144.0, 9147.17675409213, 8732.388732788708, 8901.109889011079, 8903.332250081237, 8913.1086891311, 8916.554172291391, 8964.820703585958, 9007.909920900773, 9019.143181397789, 9021.684241051564, 9027.0, 8925.910740892572, 8956.955215223947, 8981.101889811032, 8985.685501007467, 8989.775255618597, 8837.204651581324, 8933.59798809055, 8935.285177185875, 8938.86591701125, 8938.955305223431, 42442.57557424448, 42857.0, 42860.0, 42865.14269714619, 42900.78549607263, 3279.7868138571157, 3397.8640854365794, 3447.9482807757904, 3461.3769521486124, 3466.930661386758, 32647.0, 33127.83436082828, 33567.66432335694, 33730.1567460813, 34059.659403405894, 3948.1314110895555, 3979.621935916083, 4142.316517774563, 4185.665146788249, 4278.165757677254, 357991.0, 358862.4966877819, 359216.0, 360391.1882643551, 360495.0, 55061.449385505635, 63958.0, 64532.12807231581, 64585.677071614344, 64659.67670161619, 65434.0, 67488.0, 67601.66199168973, 72669.6366518164, 72865.0, 15843.287052082684, 15870.68258634844, 15923.0, 15931.761023584655, 15940.601484962737, 10412.479376031168, 12014.459349329127, 12353.691157720948, 12431.751364972832, 12483.750324993449, 19605.901970490057, 20327.796722032886, 20473.283435079702, 20521.0, 20540.794592054186, 6939.271376505394, 7069.469789765788, 7124.0, 7176.282371762886, 7229.783106506813, 1003.8544411060356, 1074.1782536359692, 1075.2741899217997, 1090.5092708281147, 1121.9719507012226, 33862.32275354479, 33949.0, 34063.489047664305, 34167.48748768771, 34251.657483424846, 1142.6115120858947, 1171.3264872698232, 1180.415694231361, 1206.2943178240828, 1252.2236213547658, 17286.481405557854, 17935.37226197077, 17993.100344982697, 18071.186796594186, 18080.36718714865, 498129.0, 499624.0, 500282.0, 500841.0, 501060.0, 59938.70030649819, 61537.69231153816, 61546.38453615496, 61560.0, 61728.0, 1283.8074288856747, 1392.8816050635608, 1401.2853444743255, 1408.1762169131173, 1409.239010934084, 33108.0, 35025.29949401048, 35100.82449587736, 35353.469697955064, 35358.46962295516, 51910.0, 52351.0, 52451.0, 52490.47509525008, 52659.47340526699, 388433.0, 390585.0, 391014.0, 391459.0, 391997.0, 23897.0, 28782.280442989093, 28854.134375968755, 30468.84765576158, 30784.846075769477, 50033.49966500434, 50312.245316321016, 50358.0, 51215.743921280155, 51355.22967155421, 41424.0, 42191.1561768769, 42484.78757606192, 43054.35418468788, 43407.78296108625, 41167.0, 42132.78933605435, 42269.0, 42477.78761106298, 42799.3580096305, 42437.1512569753, 42771.0, 43602.34596481119, 43671.34492982544, 43738.0, 35699.82150089233, 37188.0, 37298.440523391626, 37667.43498847574, 38301.4254786184, 29007.564886526296, 29211.0, 29276.0, 29456.852715736284, 29467.263318417212], "value": 20554.782495717864, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.8122273142988, 404.6196575219292, 418.15949940619356, 421.41844254928196, 421.4647397804788, 14116.717665646682, 17903.64192716145, 19702.11340489678, 19727.90136049321, 20023.0, 15148.318325675371, 18772.061396930134, 18836.811631883676, 19252.22991080355, 20050.0, 5592.86017849554, 5623.522000629951, 5651.039323315046, 5653.830385088433, 5782.566307526931, 5598.4961353478175, 5677.57418193635, 5702.857428564298, 5747.482726554609, 5748.597598168109, 4106.3635136553885, 4347.478302603676, 5933.258342707161, 6228.781992630259, 6241.531885108612, 6225.844353891143, 6299.716512756937, 6323.20959880015, 6340.619562826222, 6342.778002769904, 5455.754491047913, 5562.249096371978, 5578.721063946787, 5602.243697100921, 5604.1593760935775, 23408.18071367511, 23420.53158936829, 23571.76428235713, 23861.16485923002, 24109.276721698378, 2277.555876604063, 2280.9771902281095, 2305.0, 2463.3964678653842, 2883.889702464566, 9793.265505087147, 10036.24728145392, 10417.9479102604, 13917.512887048904, 15300.234988250542, 1959.12818795636, 1979.9208031678716, 2032.867863588862, 2039.775624681284, 2054.3939537836227, 68839.31160688378, 76137.23862761357, 82052.0, 83372.99999999939, 84277.0, 11330.0, 11421.543138274543, 11487.42562871853, 11503.884961150448, 11726.824097638371, 15933.203339832962, 15938.681226375407, 16045.919770401306, 16059.036457812568, 16083.195840208176, 7611.162772095176, 7655.387568994467, 7687.0, 8001.279884810278, 8003.5197888126895, 7164.426845852319, 7164.892526612105, 7210.170830354471, 7286.089238845145, 7321.890171647429, 4476.485204201558, 4816.518348165156, 4907.7300748458465, 4982.07831551157, 5030.748462576857, 1850.3616252392903, 1884.2180495094383, 2036.4705176653881, 2096.5806838632334, 2115.7672656007826, 921.6589861751195, 1004.8995100490039, 1013.6097602422973, 1028.0, 1078.1428764132534, 19104.705905723113, 19295.710564341545, 19298.0, 19471.707924381146, 19762.20951161966, 1009.9192064634811, 1018.495844556949, 1024.51335615582, 1040.1418829465722, 1047.0890325416783, 10781.407022613676, 11069.335839849635, 11154.944225278821, 11184.993350598485, 11195.944020279847, 360166.0, 360597.0, 362268.0, 462887.0, 475136.0, 98435.50782246186, 106783.46608267064, 107347.46326268319, 107940.0, 108397.0, 1076.6769969009265, 1112.7607564373861, 1114.9052330551995, 1124.2130508643982, 1125.2798209146154, 16339.591510211865, 16388.098654574114, 16457.91771041185, 16698.0, 17256.741148882527, 42359.0, 42666.0, 42952.57047429486, 43007.569924301606, 43266.783666081465, 57990.710046449494, 58076.4192358071, 59607.403925960185, 59955.400445996725, 60160.0, 8383.622736976973, 8503.0, 8562.058173600773, 8891.599878005376, 8934.508602026692, 12125.939370303093, 13704.588862334145, 13787.724245514833, 13815.930920345334, 13929.0, 12009.579664711693, 12188.878111219128, 12190.268583884996, 12312.75374492523, 12402.379881006094, 11333.773324533628, 11754.0, 12283.815742763687, 12597.74804503923, 12602.180858244184, 12826.871731282941, 12831.0, 12884.48462061507, 12991.220526768424, 13122.540711075066, 8955.462672239686, 9146.95426522863, 9167.404118732262, 9229.49237791928, 9471.289653275893, 9055.36750004952, 12491.312977786309, 12676.746465070832, 12693.111482196158, 12787.296698681661], "value": 9385.402908178916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.5081446086645, 1489.047009913655, 1531.7166324230018, 1674.4892807693648, 1844.8616353773461, 7794.610269486531, 8068.475549089312, 8078.313343365814, 8249.29880960119, 8269.669213231464, 8347.0, 8511.10633383495, 8518.23335899769, 8518.659253629847, 8521.488710677364, 2310.376198426428, 2329.95152181519, 2330.2077293720217, 2331.8834058297016, 2332.148765700524, 2299.9195028174017, 2323.175272778165, 2324.3491822289784, 2326.9185578504757, 2330.067972810872, 2299.1722979727283, 2302.4474126209757, 2323.163661082009, 2330.0446816805115, 2330.9533809323802, 2190.7371115466244, 2231.933042008742, 2239.2498512998163, 2249.0778780699916, 2249.55008998201, 2190.167736260211, 2202.4383782135615, 2207.9006444710026, 2208.182972300243, 2208.447888027993, 8401.747947561584, 8412.0, 8456.365772567082, 8488.787780305542, 8775.297976161892, 5104.106781313255, 5225.634205605609, 5227.085260079471, 5229.869253268699, 5231.869203269911, 3398.4222682144095, 3430.365382404263, 3514.2268700885766, 3517.8944631661093, 3526.6473352664784, 1064.4252103863905, 1082.44795154471, 1124.7806677697852, 1130.9830352544636, 1140.7775483780665, 12270.693232669166, 12386.009119270528, 12403.503859845596, 12422.06834487408, 12427.502899883993, 4340.457442819647, 4393.956060439355, 4422.049259409178, 4429.180601588717, 4532.116237333754, 9740.17208537268, 9827.606895724091, 9828.754281142885, 9831.213502919749, 9848.409095454152, 5179.637425380262, 5214.60890433219, 5233.979374022106, 5246.685198888079, 5247.396549396868, 4016.7188296819527, 4761.642876784255, 4900.534449227318, 4921.557059864629, 4931.605471562267, 3629.6551827576336, 3664.6152154023976, 3676.724245681531, 3701.0932321580844, 3745.737798354089, 1113.8774734779167, 1137.3289759042038, 1144.6794897428733, 1149.4769879704713, 1159.5187996981326, 607.8601921558067, 935.7988032573029, 945.1257586732245, 985.7584891701435, 995.049727510226, 5614.29821272341, 5817.156512305776, 5858.179854820329, 6121.602095863755, 6207.348228435949, 971.50453268832, 987.8073775613686, 988.5304480371766, 1021.4637315409469, 1023.8720159980003, 3917.4123881418013, 3932.6460618543897, 3969.702272329529, 4102.794860257035, 4220.704550681484, 10855.131589472823, 11119.27724697892, 11164.665060048212, 11207.215494915441, 11234.49444774987, 10933.617323393642, 11035.72410689723, 11041.33751974868, 11045.613403530839, 11050.613228536962, 8181.877271840927, 8212.95791404357, 8275.213854683796, 8488.957555212184, 8506.106858779738, 5800.941990580209, 5898.056310990221, 5900.291964964232, 5913.58604897653, 6032.728527216198, 10158.847617285599, 10312.329698569569, 10320.741981450525, 10334.121599664113, 10362.067413932782, 9972.451515166735, 10425.374477531372, 10529.894701052892, 10568.947155264175, 10580.576776929145, 6341.365863413622, 6822.181338239443, 6889.965550172217, 7030.543014704028, 7039.683214255473, 8304.833903321778, 8341.833163336578, 8354.958225209079, 8395.454295470774, 8421.410501265098, 7638.197989211053, 7733.961330193313, 7804.141544430222, 7810.336121429515, 7979.48133371329, 7040.401565866959, 7216.530925489827, 7304.707811687473, 7320.780376588711, 7466.104067511933, 6864.622445765531, 6908.48186386013, 6942.930570694229, 6944.166699996033, 6957.0, 5096.541311282002, 5171.612129090408, 5200.6879587224885, 5263.6841789492755, 5310.840674779763, 5176.767045482887, 5265.3944796348915, 5280.577553795764, 5290.576753859759, 5372.785088596413], "value": 4562.880028613907, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.96819321302377, 327.22121351184177, 328.942435073862, 341.57644520794224, 342.53757427472897, 5280.491766410953, 5303.602229832759, 5305.442928492511, 5313.362396512419, 5313.548348390382, 5182.533571978521, 5223.686578805275, 5259.0, 5269.7603518504575, 5274.129768588178, 3869.7484663496925, 4003.939940900889, 4119.546849846514, 4135.32519329672, 4170.582941705836, 4090.754554726711, 4111.917761644765, 4130.256553820326, 4148.875533733978, 4162.521310049353, 3892.299386110499, 4064.4512990746166, 4085.8569950051756, 4098.221337945795, 4102.446169767088, 3995.480587523618, 4073.7555746655, 4089.5092588889224, 4097.344424892003, 4098.897527561805, 3622.728295377831, 3623.673869351771, 3625.8368373422995, 3634.60019397866, 3653.5798383186007, 4999.775145031471, 5025.899482010376, 5042.747862606855, 5047.722375269359, 5053.267276244961, 4562.77186140695, 4575.428071491064, 4684.976575117103, 4692.671512994092, 4717.952820471785, 5363.973180134113, 5403.244345383843, 5411.0, 5430.506610682054, 5434.0, 3973.8807835764974, 3982.7411218270718, 3988.4017397390335, 3990.4213888986224, 3991.840326386941, 5315.495027972336, 5643.379228284885, 5665.773369065232, 5693.914591281134, 5701.375108094173, 3972.841086356513, 3982.422548730418, 3985.6811455083525, 4011.197760447922, 4012.29784787661, 4030.7380020299174, 4033.6285662874175, 4035.5157381114454, 4049.7570145791346, 4077.857274995361, 4470.742275151093, 4628.3983082199775, 4631.610516844933, 4640.535946405333, 4688.640294314679, 5478.534324582375, 5482.91775623366, 5491.0, 5499.945000549943, 5507.614466987273, 1394.3237529798039, 1400.8949328800177, 1403.4596680278166, 1406.0790182430524, 1411.1110000699493, 882.5322579033123, 891.5809569502453, 950.5152372290044, 969.3602222533235, 973.4986481961795, 5193.85735138269, 5205.115130427836, 5220.0, 5253.684778913277, 5262.947370526245, 5501.559875209973, 5509.162781167702, 5555.0, 5556.666600004012, 5566.749496272678, 5125.487451254845, 5257.185136303879, 5260.210968354778, 5264.684118952875, 5291.285676433632, 2939.8383088929886, 2944.8380339081127, 2956.0392872316584, 2959.8224106553675, 2966.46603611352, 5506.788506528557, 5727.3699893011735, 5728.885422291531, 5753.769849205984, 5795.362510123883, 5609.80365687199, 5612.747426365823, 5624.6625202487985, 5641.576881733887, 5651.152327150878, 5709.886572118397, 5717.714114294268, 5736.823951090017, 5736.971315143398, 5755.62588431759, 5332.840014799563, 5545.639533430314, 5595.496405323541, 5713.628614140068, 5720.0, 5594.972025140011, 5596.552275818006, 5612.635178713371, 5626.38109807929, 5634.295713035871, 5736.627119237236, 5852.209951656472, 5857.8535536610225, 5929.703514824328, 5960.314563825215, 4033.132876431464, 4038.1721747042375, 4053.756774593534, 4069.491313585802, 4080.489938757655, 5642.322921249476, 5644.915326270191, 5692.686902220417, 5710.743016564182, 5726.914096288642, 5324.494173053554, 5499.807506737245, 5516.14499752539, 5578.191162281365, 5613.46672066153, 5407.83776486706, 5523.254360661259, 5548.445155484419, 5552.333719953632, 5559.916601250903, 5232.7645255964335, 5307.203919412119, 5404.513593776579, 5452.482014208644, 5491.258680078138, 5530.391656917816, 5533.5849811263515, 5539.224508568885, 5572.637778544382, 5576.302962129734, 5439.211314359475, 5445.210444485607, 5469.781208751605, 5479.506844384025, 5511.83069734896], "value": 4186.373720385616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.7548908860076, 815.2296080204208, 842.0568962761706, 854.7948492361832, 882.7484167012399, 15674.059556426608, 16295.674086518293, 16338.0, 16355.75466368004, 16396.180190990446, 13814.1020833646, 13854.445822167101, 13865.0, 13870.4451821927, 13895.444182232699, 659.8284446044015, 688.6625553478784, 776.0725932510652, 809.6478032056045, 812.7358608452247, 798.1061211443163, 803.3533005930253, 805.2269820971845, 810.5784991804259, 811.2050190813006, 864.0711235421946, 867.5055218525443, 868.4224990381389, 868.8653258744905, 869.2003356911625, 703.9260877607854, 816.0411516468156, 859.1623167411786, 859.8925134358205, 860.6600392844791, 626.4894111299277, 627.2598333965918, 627.5136769004013, 627.5293529852611, 628.1927722876128, 14918.17950012749, 14941.925290373587, 14947.878909081754, 14994.850051499454, 14998.925005375013, 13631.795523067161, 13842.377093030736, 13890.23603701796, 13901.443942242298, 13961.441542338396, 11827.408629568487, 11847.526098956117, 12026.338551379671, 12111.091668124925, 12427.565035223724, 4503.774811259456, 4566.13243483737, 4622.745748983804, 4687.812487500496, 4694.0611877624615, 61076.69461652708, 61604.0, 62243.68878155626, 62900.0, 62993.0, 10308.948455257676, 10425.895741042645, 10569.471526423802, 10599.788004240027, 10600.681979540626, 18645.254189832256, 18781.15484803187, 19172.808271917103, 19297.807021929602, 19306.4208073758, 8496.66013359471, 8506.149385061444, 8615.0, 8623.180797824196, 8714.259287960467, 4643.953560464352, 4749.097671442415, 4766.6901651392545, 4801.4958429364415, 4854.150523658416, 3413.232022794851, 3465.4282043462545, 3471.9826400867832, 3480.19955410252, 3497.108237399447, 543.9836804895859, 611.4680228201494, 621.1304174156218, 657.1227411405827, 696.0568429777679, 614.50839328537, 702.9578225306395, 709.9148102227663, 725.9274072592699, 726.9963650181716, 9482.668106616235, 9735.123838854537, 9755.463449510346, 9765.951170244103, 9779.0, 673.1955313400538, 678.8268991407159, 733.7431898835365, 765.2615226306641, 778.7741554949115, 4222.3244280914905, 4233.216854881856, 4340.60934515895, 4376.299792033259, 4547.113312903952, 73572.0, 74217.0, 77342.6132869332, 77466.0, 77642.6117869407, 66564.6671766638, 67532.3246767526, 67763.3223667757, 67990.32009679939, 68892.0, 60450.0, 60588.39411605916, 60677.0, 61355.0, 61594.69202653958, 9432.0, 9461.952690236505, 9488.620455181715, 9495.287853410884, 9565.091316324939, 39593.208135837696, 40026.39960400655, 40177.39733903935, 40870.79564602158, 41339.7933010333, 44156.33765493584, 45302.773486132355, 46134.30798538092, 49370.75314623404, 56002.0, 11521.711957201138, 11589.768204635691, 11856.585019524275, 11944.880551194725, 12188.390580471118, 12685.0, 12779.97760179202, 12894.419751111034, 12916.935415322863, 12989.28558929268, 9785.412875227508, 12328.691782705504, 13468.46126154982, 13777.655558611115, 14104.717905642035, 12563.811542826683, 12856.164349317265, 13842.446302148204, 13902.304884755924, 14272.500462483764, 12775.80836287475, 12870.356482176041, 13277.203367797963, 13438.731225375634, 13536.187828730308, 10795.40625265618, 11170.832437513282, 11306.152038596974, 11643.417829108681, 11706.180567360203, 11053.447327633747, 11087.0, 11100.333979961228, 11199.10407167441, 11209.439528023404], "value": 7050.261632025212, "unit": "score"}], "name": "dromaeo_css", "value": 7188.223130267638, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 109.0, 108.0, 109.0, 109.0, 109.0, 110.0, 109.0, 108.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 124.0, 147.0, 215.0, 123.0, 150.0, 151.0, 131.0, 182.0, 123.0], "value": 151.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 159.0, 162.0, 160.0, 160.0, 161.0, 160.0, 160.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 153.0, 76.0, 75.0, 76.0, 74.0, 139.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 146.0, 92.0, 90.0, 91.0, 127.0, 91.0, 91.0, 119.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 53.0, 55.0, 54.0, 56.0, 106.0, 52.0, 52.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 77.0, 79.0, 79.0, 79.0, 79.0, 78.0, 79.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 122.0, 119.0, 127.0, 117.0, 120.0, 117.0, 190.0, 127.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 162.0, 159.0, 161.0, 159.0, 159.0, 160.0, 160.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 63.0, 62.0, 63.0, 62.0, 66.0, 61.0, 62.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1516.0, "alertThreshold": 2.0}]} 10:27:53 INFO - Return code: 0 10:27:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:27:53 ERROR - # TBPL SUCCESS # 10:27:53 INFO - Running post-action listener: _resource_record_post_action 10:27:53 INFO - ##### 10:27:53 INFO - ##### Finished run-tests step (success) 10:27:53 INFO - ##### 10:27:53 INFO - Running post-run listener: _resource_record_post_run 10:27:53 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537468928; Read time: 16; Write time: 410256 10:27:53 INFO - TinderboxPrint: CPU usage
13.1% 10:27:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 10:27:53 INFO - TinderboxPrint: I/O write bytes / time
537,468,928 / 410,256 10:27:53 INFO - TinderboxPrint: CPU idle
8,271.2 (86.9%) 10:27:53 INFO - TinderboxPrint: CPU user
1,199.6 (12.6%) 10:27:53 INFO - TinderboxPrint: Swap in / out
0 / 0 10:27:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246337536; Read time: 0; Write time: 250848 10:27:53 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291131392; Read time: 16; Write time: 159408 10:27:54 INFO - Running post-run listener: _upload_blobber_files 10:27:54 INFO - Blob upload gear active. 10:27:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:27:54 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 10:27:54 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:27:54 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:27:54 INFO - (blobuploader) - INFO - Open directory for files ... 10:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:58 INFO - (blobuploader) - INFO - Done attempting. 10:27:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:27:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:27:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:59 INFO - (blobuploader) - INFO - Done attempting. 10:27:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:27:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:28:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:01 INFO - (blobuploader) - INFO - Done attempting. 10:28:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:28:01 INFO - Return code: 0 10:28:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:28:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:28:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e710a078db0dc029e2a115fc1e8b964013324e3ccf3333df4491a0a9a6a24fd34d3ad57dfdf59a0b08274cf24007422268744f0a4373f09ecf305e089943986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/095d33fcfa4cde2c85ce3e62236f5e41014d06d2f786f2631ca504d20f48a1a31448f82d02af4957bf9bf0236c208e10618211e18dd245924429d89fdbb9e06f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84798fb7eba04608df685be8ac9134aa57777acd605b94a442e72bf1d7c924c259f6a2c0436e9616eea9dcfc7b182ab70de7699eb497fdd7e8dead65b9788feb"} 10:28:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:28:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:28:01 INFO - Contents: 10:28:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e710a078db0dc029e2a115fc1e8b964013324e3ccf3333df4491a0a9a6a24fd34d3ad57dfdf59a0b08274cf24007422268744f0a4373f09ecf305e089943986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/095d33fcfa4cde2c85ce3e62236f5e41014d06d2f786f2631ca504d20f48a1a31448f82d02af4957bf9bf0236c208e10618211e18dd245924429d89fdbb9e06f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84798fb7eba04608df685be8ac9134aa57777acd605b94a442e72bf1d7c924c259f6a2c0436e9616eea9dcfc7b182ab70de7699eb497fdd7e8dead65b9788feb"} 10:28:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:28:01 INFO - Copying logs to upload dir... 10:28:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:28:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.845578 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-17 10:28:01.381790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 10:28:01.385346) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 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/sha512/9e710a078db0dc029e2a115fc1e8b964013324e3ccf3333df4491a0a9a6a24fd34d3ad57dfdf59a0b08274cf24007422268744f0a4373f09ecf305e089943986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/095d33fcfa4cde2c85ce3e62236f5e41014d06d2f786f2631ca504d20f48a1a31448f82d02af4957bf9bf0236c208e10618211e18dd245924429d89fdbb9e06f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84798fb7eba04608df685be8ac9134aa57777acd605b94a442e72bf1d7c924c259f6a2c0436e9616eea9dcfc7b182ab70de7699eb497fdd7e8dead65b9788feb"} build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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/sha512/9e710a078db0dc029e2a115fc1e8b964013324e3ccf3333df4491a0a9a6a24fd34d3ad57dfdf59a0b08274cf24007422268744f0a4373f09ecf305e089943986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/095d33fcfa4cde2c85ce3e62236f5e41014d06d2f786f2631ca504d20f48a1a31448f82d02af4957bf9bf0236c208e10618211e18dd245924429d89fdbb9e06f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84798fb7eba04608df685be8ac9134aa57777acd605b94a442e72bf1d7c924c259f6a2c0436e9616eea9dcfc7b182ab70de7699eb497fdd7e8dead65b9788feb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 10:28:01.416111) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:28:01.416549) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463504715.348241-216975661 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:28:01.497771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 10:28:01.498118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 10:28:01.498418) ========= ========= Total master_lag: 0.17 =========