builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1463766702.31 results: success (0) buildid: 20160520080026 builduid: 1c425cc5fbb948099c93908d83ffd7c2 revision: 8b9e82bf88a93b825751717e92956e7568c81217 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.314874) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.315308) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.315577) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.331777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.332033) ========= 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=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 10:51:42-- 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% 46.1M=0s 2016-05-20 10:51:42 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111269 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.456548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.456865) ========= 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=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020176 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.506296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:42.506622) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8b9e82bf88a93b825751717e92956e7568c81217 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8b9e82bf88a93b825751717e92956e7568c81217 --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=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 10:51:42,964 truncating revision to first 12 chars 2016-05-20 10:51:42,964 Setting DEBUG logging. 2016-05-20 10:51:42,964 attempt 1/10 2016-05-20 10:51:42,964 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/8b9e82bf88a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 10:51:43,209 unpacking tar archive at: mozilla-inbound-8b9e82bf88a9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.547386 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:43.066711) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:43.067029) ========= script_repo_revision: 8b9e82bf88a93b825751717e92956e7568c81217 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:43.067410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:43.067691) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 10:51:43.075476) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-20 10:51:43.075832) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:51:43 INFO - MultiFileLogger online at 20160520 10:51:43 in /builds/slave/test 10:51:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 10:51:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:51:43 INFO - {'append_to_log': False, 10:51:43 INFO - 'base_work_dir': '/builds/slave/test', 10:51:43 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 10:51:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:51:43 INFO - 'branch': 'Mozilla-Inbound', 10:51:43 INFO - 'buildbot_json_path': 'buildprops.json', 10:51:43 INFO - 'config_files': ('talos/linux_config.py',), 10:51:43 INFO - 'default_actions': ('clobber', 10:51:43 INFO - 'read-buildbot-config', 10:51:43 INFO - 'download-and-extract', 10:51:43 INFO - 'populate-webroot', 10:51:43 INFO - 'create-virtualenv', 10:51:43 INFO - 'install', 10:51:43 INFO - 'run-tests'), 10:51:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:51:43 INFO - 'download_minidump_stackwalk': True, 10:51:43 INFO - 'download_symbols': 'ondemand', 10:51:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:51:43 INFO - 'tooltool.py': '/tools/tooltool.py', 10:51:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:51:43 INFO - '/tools/misc-python/virtualenv.py')}, 10:51:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:51:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:51:43 INFO - 'installer_path': 'installer.exe', 10:51:43 INFO - 'log_level': 'info', 10:51:43 INFO - 'log_name': 'talos', 10:51:43 INFO - 'log_to_console': True, 10:51:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:51:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:51:43 INFO - 'opt_config_files': (), 10:51:43 INFO - 'pip_index': False, 10:51:43 INFO - 'sps_profile': False, 10:51:43 INFO - 'sps_profile_interval': 0, 10:51:43 INFO - 'suite': 'dromaeojs', 10:51:43 INFO - 'system_bits': '32', 10:51:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:51:43 INFO - 'title': 'talos-linux64-ix-001', 10:51:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:51:43 INFO - 'use_talos_json': True, 10:51:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:51:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:51:43 INFO - 'work_dir': 'build'} 10:51:43 INFO - ##### 10:51:43 INFO - ##### Running clobber step. 10:51:43 INFO - ##### 10:51:43 INFO - Running pre-action listener: _resource_record_pre_action 10:51:43 INFO - Running main action method: clobber 10:51:43 INFO - rmtree: /builds/slave/test/build 10:51:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:51:44 INFO - Running post-action listener: _resource_record_post_action 10:51:44 INFO - ##### 10:51:44 INFO - ##### Finished clobber step (success) 10:51:44 INFO - ##### 10:51:44 INFO - ##### 10:51:44 INFO - ##### Running read-buildbot-config step. 10:51:44 INFO - ##### 10:51:44 INFO - Running pre-action listener: _resource_record_pre_action 10:51:44 INFO - Running main action method: read_buildbot_config 10:51:44 INFO - Using buildbot properties: 10:51:44 INFO - { 10:51:44 INFO - "project": "", 10:51:44 INFO - "product": "firefox", 10:51:44 INFO - "script_repo_revision": "production", 10:51:44 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 10:51:44 INFO - "repository": "", 10:51:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 10:51:44 INFO - "buildid": "20160520080026", 10:51:44 INFO - "pgo_build": "True", 10:51:44 INFO - "basedir": "/builds/slave/test", 10:51:44 INFO - "buildnumber": 1840, 10:51:44 INFO - "slavename": "talos-linux64-ix-001", 10:51:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:51:44 INFO - "platform": "ubuntu64_hw", 10:51:44 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 10:51:44 INFO - "branch": "mozilla-inbound", 10:51:44 INFO - "repo_path": "integration/mozilla-inbound", 10:51:44 INFO - "revision": "8b9e82bf88a93b825751717e92956e7568c81217", 10:51:44 INFO - "stage_platform": "linux64-pgo", 10:51:44 INFO - "builduid": "1c425cc5fbb948099c93908d83ffd7c2", 10:51:44 INFO - "slavebuilddir": "test" 10:51:44 INFO - } 10:51:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:51:44 INFO - Running post-action listener: _resource_record_post_action 10:51:44 INFO - ##### 10:51:44 INFO - ##### Finished read-buildbot-config step (success) 10:51:44 INFO - ##### 10:51:44 INFO - ##### 10:51:44 INFO - ##### Running download-and-extract step. 10:51:44 INFO - ##### 10:51:44 INFO - Running pre-action listener: _resource_record_pre_action 10:51:44 INFO - Running main action method: download_and_extract 10:51:44 INFO - mkdir: /builds/slave/test/build/tests 10:51: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:51:44 INFO - https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:51:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:51:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:51:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:45 INFO - Downloaded 1361 bytes. 10:51:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:51:45 INFO - Using the following test package requirements: 10:51:45 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:51:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:51:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:51:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'jsshell-linux-x86_64.zip'], 10:51:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:51:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:51:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:51:45 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:51:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:51:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:51:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:51:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:51:45 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:51:45 INFO - https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:51:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:51:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:51:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:46 INFO - Downloaded 24025049 bytes. 10:51:46 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:51:46 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:51:47 INFO - Return code: 0 10:51:47 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:51:47 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:51:47 INFO - https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:51:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:51:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:51:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:49 INFO - Downloaded 24025049 bytes. 10:51:49 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:51:49 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:51:50 INFO - Return code: 0 10:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:50 INFO - https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:51:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:51:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:51:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:51 INFO - Downloaded 10840194 bytes. 10:51:51 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:51:51 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:51:51 INFO - Return code: 0 10:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:51 INFO - https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:51:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:51:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:51:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RUNZE3WHQOuP_nEzjDj3RA/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:51:53 INFO - Downloaded 58424191 bytes. 10:51:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:51:53 INFO - mkdir: /builds/slave/test/properties 10:51:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:51:53 INFO - Writing to file /builds/slave/test/properties/build_url 10:51:53 INFO - Contents: 10:51:53 INFO - build_url:https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:51:54 INFO - Running post-action listener: _resource_record_post_action 10:51:54 INFO - Running post-action listener: set_extra_try_arguments 10:51:54 INFO - ##### 10:51:54 INFO - ##### Finished download-and-extract step (success) 10:51:54 INFO - ##### 10:51:54 INFO - ##### 10:51:54 INFO - ##### Running populate-webroot step. 10:51:54 INFO - ##### 10:51:54 INFO - Running pre-action listener: _resource_record_pre_action 10:51:54 INFO - Running main action method: populate_webroot 10:51:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:51:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:51:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:51:54 INFO - '--tppagecycles', 10:51:54 INFO - '7'], 10:51:54 INFO - 'tests': ['tsvgm']}}, 10:51:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:51:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:51:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:51:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:51:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['damp', 'tps']}, 10:51:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'tests': ['damp', 'tps']}, 10:51:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['dromaeo_dom']}, 10:51:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:51:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['a11yr', 10:51:54 INFO - 'ts_paint', 10:51:54 INFO - 'tpaint', 10:51:54 INFO - 'sessionrestore', 10:51:54 INFO - 'sessionrestore_no_auto_restore', 10:51:54 INFO - 'tabpaint']}, 10:51:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['tsvgx', 10:51:54 INFO - 'tsvgr_opacity', 10:51:54 INFO - 'tart', 10:51:54 INFO - 'tscrollx', 10:51:54 INFO - 'cart']}, 10:51:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:51:54 INFO - 'tsvgr_opacity', 10:51:54 INFO - 'tart', 10:51:54 INFO - 'tscrollx', 10:51:54 INFO - 'cart']}, 10:51:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'talos_options': ['--disable-e10s'], 10:51:54 INFO - 'tests': ['tp5o']}, 10:51:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'tests': ['tp5o']}, 10:51:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'talos_options': ['--disable-e10s', 10:51:54 INFO - '--xperf_path', 10:51:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:54 INFO - 'tests': ['tp5n']}, 10:51:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:54 INFO - 'talos_options': ['--xperf_path', 10:51:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:54 INFO - 'tests': ['tp5n']}}, 10:51:54 INFO - 'talos.zip': {'path': '', 10:51:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:51:54 INFO - Running post-action listener: _resource_record_post_action 10:51:54 INFO - ##### 10:51:54 INFO - ##### Finished populate-webroot step (success) 10:51:54 INFO - ##### 10:51:54 INFO - ##### 10:51:54 INFO - ##### Running create-virtualenv step. 10:51:54 INFO - ##### 10:51:54 INFO - Running pre-action listener: _resource_record_pre_action 10:51:54 INFO - Running main action method: create_virtualenv 10:51:54 INFO - Creating virtualenv /builds/slave/test/build/venv 10:51:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:51:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:51:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:51:54 INFO - Using real prefix '/usr' 10:51:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:51:55 INFO - Installing distribute.............................................................................................................................................................................................done. 10:51:57 INFO - Installing pip.................done. 10:51:57 INFO - Return code: 0 10:51:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:51: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:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51: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:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:51:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:51:57 INFO - Using env: {'DISPLAY': ':0', 10:51:57 INFO - 'HOME': '/home/cltbld', 10:51:57 INFO - 'LANG': 'en_US.UTF-8', 10:51:57 INFO - 'LANGUAGE': 'en_US:en', 10:51:57 INFO - 'LOGNAME': 'cltbld', 10:51:57 INFO - 'MAIL': '/var/mail/cltbld', 10:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:57 INFO - 'MOZ_NO_REMOTE': '1', 10:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:57 INFO - 'NO_EM_RESTART': '1', 10:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:57 INFO - 'PWD': '/builds/slave/test', 10:51:57 INFO - 'SHELL': '/bin/bash', 10:51:57 INFO - 'SHLVL': '1', 10:51:57 INFO - 'TERM': 'linux', 10:51:57 INFO - 'TMOUT': '86400', 10:51:57 INFO - 'USER': 'cltbld', 10:51:57 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:57 INFO - '_': '/tools/buildbot/bin/python'} 10:51:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:51:57 INFO - Cleaning up... 10:51:57 INFO - Return code: 0 10:51:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:51: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:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51: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:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:51:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:51:57 INFO - Using env: {'DISPLAY': ':0', 10:51:57 INFO - 'HOME': '/home/cltbld', 10:51:57 INFO - 'LANG': 'en_US.UTF-8', 10:51:57 INFO - 'LANGUAGE': 'en_US:en', 10:51:57 INFO - 'LOGNAME': 'cltbld', 10:51:57 INFO - 'MAIL': '/var/mail/cltbld', 10:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:57 INFO - 'MOZ_NO_REMOTE': '1', 10:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:51:57 INFO - 'NO_EM_RESTART': '1', 10:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:57 INFO - 'PWD': '/builds/slave/test', 10:51:57 INFO - 'SHELL': '/bin/bash', 10:51:57 INFO - 'SHLVL': '1', 10:51:57 INFO - 'TERM': 'linux', 10:51:57 INFO - 'TMOUT': '86400', 10:51:57 INFO - 'USER': 'cltbld', 10:51:57 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:57 INFO - '_': '/tools/buildbot/bin/python'} 10:51:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:57 INFO - Downloading/unpacking psutil>=3.1.1 10:51: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:51: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:51: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:51: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:51: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:51: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:51:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:51:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:59 INFO - Installing collected packages: psutil 10:51:59 INFO - Running setup.py install for psutil 10:51:59 INFO - building 'psutil._psutil_linux' extension 10:51:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:51:59 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:51:59 INFO - building 'psutil._psutil_posix' extension 10:51:59 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:51:59 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:51:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:59 INFO - Successfully installed psutil 10:51:59 INFO - Cleaning up... 10:52:00 INFO - Return code: 0 10:52:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:52:00 INFO - Using env: {'DISPLAY': ':0', 10:52:00 INFO - 'HOME': '/home/cltbld', 10:52:00 INFO - 'LANG': 'en_US.UTF-8', 10:52:00 INFO - 'LANGUAGE': 'en_US:en', 10:52:00 INFO - 'LOGNAME': 'cltbld', 10:52:00 INFO - 'MAIL': '/var/mail/cltbld', 10:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:00 INFO - 'MOZ_NO_REMOTE': '1', 10:52:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:00 INFO - 'NO_EM_RESTART': '1', 10:52:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:00 INFO - 'PWD': '/builds/slave/test', 10:52:00 INFO - 'SHELL': '/bin/bash', 10:52:00 INFO - 'SHLVL': '1', 10:52:00 INFO - 'TERM': 'linux', 10:52:00 INFO - 'TMOUT': '86400', 10:52:00 INFO - 'USER': 'cltbld', 10:52:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:00 INFO - '_': '/tools/buildbot/bin/python'} 10:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:52: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:52: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:52: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:52: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:52: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:52: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:52:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:52:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:52:02 INFO - Installing collected packages: mozsystemmonitor 10:52:02 INFO - Running setup.py install for mozsystemmonitor 10:52:02 INFO - Successfully installed mozsystemmonitor 10:52:02 INFO - Cleaning up... 10:52:02 INFO - Return code: 0 10:52:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:52:02 INFO - Using env: {'DISPLAY': ':0', 10:52:02 INFO - 'HOME': '/home/cltbld', 10:52:02 INFO - 'LANG': 'en_US.UTF-8', 10:52:02 INFO - 'LANGUAGE': 'en_US:en', 10:52:02 INFO - 'LOGNAME': 'cltbld', 10:52:02 INFO - 'MAIL': '/var/mail/cltbld', 10:52:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:02 INFO - 'MOZ_NO_REMOTE': '1', 10:52:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:02 INFO - 'NO_EM_RESTART': '1', 10:52:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:02 INFO - 'PWD': '/builds/slave/test', 10:52:02 INFO - 'SHELL': '/bin/bash', 10:52:02 INFO - 'SHLVL': '1', 10:52:02 INFO - 'TERM': 'linux', 10:52:02 INFO - 'TMOUT': '86400', 10:52:02 INFO - 'USER': 'cltbld', 10:52:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:02 INFO - '_': '/tools/buildbot/bin/python'} 10:52:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:04 INFO - Downloading blobuploader-1.2.4.tar.gz 10:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:52:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:52:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:05 INFO - Downloading docopt-0.6.1.tar.gz 10:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:52:05 INFO - Installing collected packages: blobuploader, requests, docopt 10:52:05 INFO - Running setup.py install for blobuploader 10:52:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:52:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:52:05 INFO - Running setup.py install for requests 10:52:05 INFO - Running setup.py install for docopt 10:52:05 INFO - Successfully installed blobuploader requests docopt 10:52:05 INFO - Cleaning up... 10:52:05 INFO - Return code: 0 10:52:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:05 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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:05 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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:05 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 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:05 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:52:05 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:52:05 INFO - Using env: {'DISPLAY': ':0', 10:52:05 INFO - 'HOME': '/home/cltbld', 10:52:05 INFO - 'LANG': 'en_US.UTF-8', 10:52:05 INFO - 'LANGUAGE': 'en_US:en', 10:52:05 INFO - 'LOGNAME': 'cltbld', 10:52:05 INFO - 'MAIL': '/var/mail/cltbld', 10:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:05 INFO - 'MOZ_NO_REMOTE': '1', 10:52:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:05 INFO - 'NO_EM_RESTART': '1', 10:52:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:05 INFO - 'PWD': '/builds/slave/test', 10:52:05 INFO - 'SHELL': '/bin/bash', 10:52:05 INFO - 'SHLVL': '1', 10:52:05 INFO - 'TERM': 'linux', 10:52:05 INFO - 'TMOUT': '86400', 10:52:05 INFO - 'USER': 'cltbld', 10:52:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:05 INFO - '_': '/tools/buildbot/bin/python'} 10:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:05 INFO - Running setup.py (path:/tmp/pip-gUl2oL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:06 INFO - Running setup.py (path:/tmp/pip-YWAgBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:06 INFO - Running setup.py (path:/tmp/pip-k33ptw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:06 INFO - Running setup.py (path:/tmp/pip-Q7FC6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:06 INFO - Running setup.py (path:/tmp/pip-u5UuWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:06 INFO - Running setup.py (path:/tmp/pip-EXSejU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:06 INFO - Running setup.py (path:/tmp/pip-wl5nZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:06 INFO - Running setup.py (path:/tmp/pip-UjyetG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:06 INFO - Running setup.py (path:/tmp/pip-yPyjJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:06 INFO - Running setup.py (path:/tmp/pip-zYLOor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:06 INFO - Running setup.py (path:/tmp/pip-vLdGmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:06 INFO - Running setup.py (path:/tmp/pip-y8lEIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:06 INFO - Running setup.py (path:/tmp/pip-rYTYuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:06 INFO - Running setup.py (path:/tmp/pip-k9mK2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:06 INFO - Running setup.py (path:/tmp/pip-lbNqbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:07 INFO - Running setup.py (path:/tmp/pip-ZLSv9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:07 INFO - Running setup.py (path:/tmp/pip-0u_sm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:52:07 INFO - Running setup.py install for manifestparser 10:52:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozcrash 10:52:07 INFO - Running setup.py install for mozdebug 10:52:07 INFO - Running setup.py install for mozdevice 10:52:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozfile 10:52:07 INFO - Running setup.py install for mozhttpd 10:52:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozinfo 10:52:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozInstall 10:52:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozleak 10:52:08 INFO - Running setup.py install for mozlog 10:52:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for moznetwork 10:52:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozprocess 10:52:08 INFO - Running setup.py install for mozprofile 10:52:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozrunner 10:52:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozscreenshot 10:52:08 INFO - Running setup.py install for moztest 10:52:08 INFO - Running setup.py install for mozversion 10:52:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:52:08 INFO - Cleaning up... 10:52:08 INFO - Return code: 0 10:52:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:08 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:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:52:09 INFO - Using env: {'DISPLAY': ':0', 10:52:09 INFO - 'HOME': '/home/cltbld', 10:52:09 INFO - 'LANG': 'en_US.UTF-8', 10:52:09 INFO - 'LANGUAGE': 'en_US:en', 10:52:09 INFO - 'LOGNAME': 'cltbld', 10:52:09 INFO - 'MAIL': '/var/mail/cltbld', 10:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:09 INFO - 'MOZ_NO_REMOTE': '1', 10:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:09 INFO - 'NO_EM_RESTART': '1', 10:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:09 INFO - 'PWD': '/builds/slave/test', 10:52:09 INFO - 'SHELL': '/bin/bash', 10:52:09 INFO - 'SHLVL': '1', 10:52:09 INFO - 'TERM': 'linux', 10:52:09 INFO - 'TMOUT': '86400', 10:52:09 INFO - 'USER': 'cltbld', 10:52:09 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:09 INFO - '_': '/tools/buildbot/bin/python'} 10:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:09 INFO - Running setup.py (path:/tmp/pip-HFtWdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:09 INFO - Running setup.py (path:/tmp/pip-heRwWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:09 INFO - Running setup.py (path:/tmp/pip-m1JbDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:09 INFO - Running setup.py (path:/tmp/pip-uceeIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:09 INFO - Running setup.py (path:/tmp/pip-mcqjpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:09 INFO - Running setup.py (path:/tmp/pip-ohQySI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:09 INFO - Running setup.py (path:/tmp/pip-QavTeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:09 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:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:09 INFO - Running setup.py (path:/tmp/pip-cIcCY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:09 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:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:09 INFO - Running setup.py (path:/tmp/pip-Z01MM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:09 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:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:09 INFO - Running setup.py (path:/tmp/pip-EXPdXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:09 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:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:09 INFO - Running setup.py (path:/tmp/pip-CBz8_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:10 INFO - Running setup.py (path:/tmp/pip-6aLHyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:10 INFO - Running setup.py (path:/tmp/pip-_z49Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:10 INFO - Running setup.py (path:/tmp/pip-OMagVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:10 INFO - Running setup.py (path:/tmp/pip-ED4j7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:10 INFO - Running setup.py (path:/tmp/pip-VX1_aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:10 INFO - Running setup.py (path:/tmp/pip-4YT9PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:52:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:12 INFO - Downloading blessings-1.6.tar.gz 10:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:52:12 INFO - Installing collected packages: blessings 10:52:12 INFO - Running setup.py install for blessings 10:52:12 INFO - Successfully installed blessings 10:52:12 INFO - Cleaning up... 10:52:12 INFO - Return code: 0 10:52:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:52:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:12 INFO - Reading from file tmpfile_stdout 10:52:12 INFO - Current package versions: 10:52:12 INFO - argparse == 1.2.1 10:52:12 INFO - blessings == 1.6 10:52:12 INFO - blobuploader == 1.2.4 10:52:12 INFO - docopt == 0.6.1 10:52:12 INFO - manifestparser == 1.1 10:52:12 INFO - mozInstall == 1.12 10:52:12 INFO - mozcrash == 0.17 10:52:12 INFO - mozdebug == 0.1 10:52:12 INFO - mozdevice == 0.48 10:52:12 INFO - mozfile == 1.2 10:52:12 INFO - mozhttpd == 0.7 10:52:12 INFO - mozinfo == 0.9 10:52:12 INFO - mozleak == 0.1 10:52:12 INFO - mozlog == 3.2 10:52:12 INFO - moznetwork == 0.27 10:52:12 INFO - mozprocess == 0.22 10:52:12 INFO - mozprofile == 0.28 10:52:12 INFO - mozrunner == 6.11 10:52:12 INFO - mozscreenshot == 0.1 10:52:12 INFO - mozsystemmonitor == 0.3 10:52:12 INFO - moztest == 0.7 10:52:12 INFO - mozversion == 1.4 10:52:12 INFO - psutil == 3.1.1 10:52:12 INFO - requests == 1.2.3 10:52:12 INFO - wsgiref == 0.1.2 10:52:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:12 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:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:12 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:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:12 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 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:12 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:52:12 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:52:12 INFO - Using env: {'DISPLAY': ':0', 10:52:12 INFO - 'HOME': '/home/cltbld', 10:52:12 INFO - 'LANG': 'en_US.UTF-8', 10:52:12 INFO - 'LANGUAGE': 'en_US:en', 10:52:12 INFO - 'LOGNAME': 'cltbld', 10:52:12 INFO - 'MAIL': '/var/mail/cltbld', 10:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:12 INFO - 'MOZ_NO_REMOTE': '1', 10:52:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:12 INFO - 'NO_EM_RESTART': '1', 10:52:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:12 INFO - 'PWD': '/builds/slave/test', 10:52:12 INFO - 'SHELL': '/bin/bash', 10:52:12 INFO - 'SHLVL': '1', 10:52:12 INFO - 'TERM': 'linux', 10:52:12 INFO - 'TMOUT': '86400', 10:52:12 INFO - 'USER': 'cltbld', 10:52:12 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:12 INFO - '_': '/tools/buildbot/bin/python'} 10:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:52:13 INFO - Cleaning up... 10:52:13 INFO - Return code: 0 10:52:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:52:13 INFO - Using env: {'DISPLAY': ':0', 10:52:13 INFO - 'HOME': '/home/cltbld', 10:52:13 INFO - 'LANG': 'en_US.UTF-8', 10:52:13 INFO - 'LANGUAGE': 'en_US:en', 10:52:13 INFO - 'LOGNAME': 'cltbld', 10:52:13 INFO - 'MAIL': '/var/mail/cltbld', 10:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:13 INFO - 'MOZ_NO_REMOTE': '1', 10:52:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:13 INFO - 'NO_EM_RESTART': '1', 10:52:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:13 INFO - 'PWD': '/builds/slave/test', 10:52:13 INFO - 'SHELL': '/bin/bash', 10:52:13 INFO - 'SHLVL': '1', 10:52:13 INFO - 'TERM': 'linux', 10:52:13 INFO - 'TMOUT': '86400', 10:52:13 INFO - 'USER': 'cltbld', 10:52:13 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:13 INFO - '_': '/tools/buildbot/bin/python'} 10:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:13 INFO - Downloading/unpacking jsonschema 10:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:15 INFO - Downloading/unpacking functools32 (from jsonschema) 10:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:15 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:15 INFO - warning: no files found matching '*.txt' 10:52:15 INFO - no previously-included directories found matching 'build' 10:52:15 INFO - no previously-included directories found matching 'dist' 10:52:15 INFO - no previously-included directories found matching '.git*' 10:52:15 INFO - Installing collected packages: jsonschema, functools32 10:52:15 INFO - Running setup.py install for functools32 10:52:15 INFO - warning: no files found matching '*.txt' 10:52:15 INFO - no previously-included directories found matching 'build' 10:52:15 INFO - no previously-included directories found matching 'dist' 10:52:15 INFO - no previously-included directories found matching '.git*' 10:52:15 INFO - Successfully installed jsonschema functools32 10:52:15 INFO - Cleaning up... 10:52:15 INFO - Return code: 0 10:52:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe322357f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2337770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, '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': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', '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:52:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:52:15 INFO - Using env: {'DISPLAY': ':0', 10:52:15 INFO - 'HOME': '/home/cltbld', 10:52:15 INFO - 'LANG': 'en_US.UTF-8', 10:52:15 INFO - 'LANGUAGE': 'en_US:en', 10:52:15 INFO - 'LOGNAME': 'cltbld', 10:52:15 INFO - 'MAIL': '/var/mail/cltbld', 10:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:15 INFO - 'MOZ_NO_REMOTE': '1', 10:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:15 INFO - 'NO_EM_RESTART': '1', 10:52:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:15 INFO - 'PWD': '/builds/slave/test', 10:52:15 INFO - 'SHELL': '/bin/bash', 10:52:15 INFO - 'SHLVL': '1', 10:52:15 INFO - 'TERM': 'linux', 10:52:15 INFO - 'TMOUT': '86400', 10:52:15 INFO - 'USER': 'cltbld', 10:52:15 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:15 INFO - '_': '/tools/buildbot/bin/python'} 10:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:15 INFO - Downloading/unpacking flake8 10:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:52:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:52:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:52:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:18 INFO - no previously-included directories found matching 'docs/_build' 10:52:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:52:18 INFO - Running setup.py install for pep8 10:52:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:18 INFO - no previously-included directories found matching 'docs/_build' 10:52:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:52:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:52:18 INFO - Cleaning up... 10:52:18 INFO - Return code: 0 10:52:18 INFO - Running post-action listener: _resource_record_post_action 10:52:18 INFO - Running post-action listener: _start_resource_monitoring 10:52:18 INFO - Starting resource monitoring. 10:52:18 INFO - ##### 10:52:18 INFO - ##### Finished create-virtualenv step (success) 10:52:18 INFO - ##### 10:52:18 INFO - ##### 10:52:18 INFO - ##### Running install step. 10:52:18 INFO - ##### 10:52:18 INFO - Running pre-action listener: _resource_record_pre_action 10:52:18 INFO - Running main action method: install 10:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:18 INFO - Reading from file tmpfile_stdout 10:52:18 INFO - Detecting whether we're running mozinstall >=1.0... 10:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:52:18 INFO - Reading from file tmpfile_stdout 10:52:18 INFO - Output received: 10:52:18 INFO - Usage: mozinstall [options] installer 10:52:18 INFO - Options: 10:52:18 INFO - -h, --help show this help message and exit 10:52:18 INFO - -d DEST, --destination=DEST 10:52:18 INFO - Directory to install application into. [default: 10:52:18 INFO - "/builds/slave/test"] 10:52:18 INFO - --app=APP Application being installed. [default: firefox] 10:52:18 INFO - mkdir: /builds/slave/test/build/application 10:52:18 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:52:18 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:52:35 INFO - Reading from file tmpfile_stdout 10:52:35 INFO - Output received: 10:52:35 INFO - /builds/slave/test/build/application/firefox/firefox 10:52:35 INFO - Running post-action listener: _resource_record_post_action 10:52:35 INFO - ##### 10:52:35 INFO - ##### Finished install step (success) 10:52:35 INFO - ##### 10:52:35 INFO - ##### 10:52:35 INFO - ##### Running run-tests step. 10:52:35 INFO - ##### 10:52:35 INFO - Running pre-action listener: _resource_record_pre_action 10:52:35 INFO - Running main action method: run_tests 10:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:52:35 INFO - Python 2.7.3 10:52:35 INFO - Return code: 0 10:52:35 INFO - grabbing minidump binary from tooltool 10:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:35 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 0x23c69c0>, '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 0x23c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c7d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:52:35 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:52:35 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:52:35 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:52:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:52:35 INFO - Return code: 0 10:52:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:52:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:52:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:52:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:52:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:52:36 INFO - Return code: 0 10:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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:52:36 INFO - Using env: {'DISPLAY': ':0', 10:52:36 INFO - 'HOME': '/home/cltbld', 10:52:36 INFO - 'LANG': 'en_US.UTF-8', 10:52:36 INFO - 'LANGUAGE': 'en_US:en', 10:52:36 INFO - 'LOGNAME': 'cltbld', 10:52:36 INFO - 'MAIL': '/var/mail/cltbld', 10:52:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:52:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:36 INFO - 'MOZ_NO_REMOTE': '1', 10:52:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:52:36 INFO - 'NO_EM_RESTART': '1', 10:52:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:36 INFO - 'PWD': '/builds/slave/test', 10:52:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:52:36 INFO - 'SHELL': '/bin/bash', 10:52:36 INFO - 'SHLVL': '1', 10:52:36 INFO - 'TERM': 'linux', 10:52:36 INFO - 'TMOUT': '86400', 10:52:36 INFO - 'USER': 'cltbld', 10:52:36 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483', 10:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:36 INFO - '_': '/tools/buildbot/bin/python'} 10:52:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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:52:36 INFO - mozversion application_buildid: 20160520080026 10:52:36 INFO - mozversion application_changeset: 8b9e82bf88a93b825751717e92956e7568c81217 10:52:36 INFO - mozversion application_display_name: Nightly 10:52:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:52:36 INFO - mozversion application_name: Firefox 10:52:36 INFO - mozversion application_remotingname: firefox 10:52:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 10:52:36 INFO - mozversion application_vendor: Mozilla 10:52:36 INFO - mozversion application_version: 49.0a1 10:52:36 INFO - mozversion platform_buildid: 20160520080026 10:52:36 INFO - mozversion platform_changeset: 8b9e82bf88a93b825751717e92956e7568c81217 10:52:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 10:52:36 INFO - mozversion platform_version: 49.0a1 10:52:36 INFO - using testdate: 1463766756 10:52:36 INFO - actual date: 1463766756 10:52:36 INFO - starting webserver on 'localhost:41926' 10:52:36 INFO - SUITE-START | Running 2 tests 10:52:36 INFO - TEST-START | dromaeo_css 10:52:36 INFO - operating with platform_type : linux_ 10:52:36 INFO - Initialising browser for dromaeo_css test... 10:52:36 INFO - TEST-INFO | started process 15117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc6rJ3A/profile http://localhost:41926/getInfo.html) 10:52:40 INFO - PROCESS | 15117 | __metrics Screen width/height:1600/1200 10:52:40 INFO - PROCESS | 15117 | colorDepth:24 10:52:40 INFO - PROCESS | 15117 | Browser inner width/height: 1024/697 10:52:40 INFO - PROCESS | 15117 | __metrics 10:52:42 ERROR - PROCESS | 15117 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:52:42 INFO - TEST-INFO | 15117: exit 0 10:52:42 INFO - Browser initialized. 10:52:42 INFO - Running cycle 1/1 for dromaeo_css test... 10:52:42 INFO - TEST-INFO | started process 15198 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc6rJ3A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:52:54 INFO - PROCESS | 15198 | RSS: Main: 152297472 10:52:54 INFO - PROCESS | 15198 | 10:53:45 INFO - PROCESS | 15198 | 1463766825667 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:55:53 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41926/tests/dromaeo/cssquery-ext.html) 10:55:54 INFO - PROCESS | 15198 | RSS: Main: 252362752 10:55:54 INFO - PROCESS | 15198 | 10:58:56 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-ext.html (next: http://localhost:41926/tests/dromaeo/cssquery-jquery.html) 10:58:57 INFO - PROCESS | 15198 | RSS: Main: 236445696 10:58:57 INFO - PROCESS | 15198 | 11:01:59 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41926/tests/dromaeo/cssquery-mootools.html) 11:02:00 INFO - PROCESS | 15198 | RSS: Main: 243396608 11:02:00 INFO - PROCESS | 15198 | 11:05:02 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41926/tests/dromaeo/cssquery-prototype.html) 11:05:02 INFO - PROCESS | 15198 | RSS: Main: 231944192 11:05:02 INFO - PROCESS | 15198 | 11:08:05 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41926/tests/dromaeo/cssquery-yui.html) 11:08:06 INFO - PROCESS | 15198 | RSS: Main: 206909440 11:08:06 INFO - PROCESS | 15198 | 11:11:08 INFO - PROCESS | 15198 | Cycle 1(1): loaded http://localhost:41926/tests/dromaeo/cssquery-yui.html (next: http://localhost:41926/tests/dromaeo/cssquery-dojo.html) 11:11:09 INFO - PROCESS | 15198 | RSS: Main: 261640192 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | __start_tp_report 11:11:09 INFO - PROCESS | 15198 | _x_x_mozilla_page_load 11:11:09 INFO - PROCESS | 15198 | _x_x_mozilla_page_load_details 11:11:09 INFO - PROCESS | 15198 | |i|pagename|runs| 11:11:09 INFO - PROCESS | 15198 | |0;dojo.html;3179.9841000795;3182.6817318268177;3192.9999999999986;3205.759568032398;3261.8695252189914;5407.459254074591;5467.20725494803;5559.305086864142;5598.4961353478175;5629.099344104944;5499.065158922983;5536.4186760390185;5563.610547261683;5614.831555053344;5618.943810561904;4965.726885021331;5056.949430505703;5077.289179514853;5092.28707980883;5219.478052194769;5130;5143.871403214912;5185.066687996159;5200.089984252741;5214.895702085956;4922.8523144305545;4986.252062190664;5033.899322013557;5034.270030845507;5071;5046.142155833517;5063.620228482879;5063.670861394035;5068.6705364151585;5103.744812759347;4907.411110666707;5023.32185155003;5026.648134630577;5047.343845300087;5059.62052846038;5459.535939445152;5490.6431081979545;5520.861978450531;5600;5621.044422448153;6004;6013.830820139914;6016.458518733335;6044.304904935901;6061.605995610315;5836.241288632493;5982.73077711504;5998.25021872266;5999.190109335227;6000.789972350947;5505.587080968903;5540.722963851791;5549.1676248562635;5644.012297847901;5647.463490968352;6110.757479312526;6132.41742034506;6132.50939924805;6133.723982420802;6136.9079463808075;5668.263125793703;5790.971045144747;5954.285485741739;5961.433663801932;5967.1347654589845;5845.269341332333;5984.132300816357;5985.40145985398;5987.820365389045;6040.939590604125;5908.675022873783;5912.083627037816;5954.76180952757;5960.195373624505;5961.970190149022;5798.246227990418;5897.557683173779;5945.881082378328;5953.315368732651;5962.55280853929;4545.6136228420955;4548.158590660738;4553.317002449659;4584.839530616412;4589.426321709786;4015.377616469452;4024.215278020758;4033.153037862023;4033.648519057708;4063.2889244382113;5467.234587157722;5509.724513774296;5541.030319694038;5572;5726.713664316851;5942.168096466498;5945.346011938684;5956.5532585055425;5974.2830860297045;6015.1278064681255;5616.971915140398;5618.185363122325;5624.0439125348785;5626.409227031103;5626.521745651666;4816.590589799836;4873.902521949542;4902.558769710672;4921.040397122527;4940.950590494121;6166.260048794084;6188.9690551546955;6192.690365481798;6194;6201.720922558496;5963;5973.731182096729;5977.820665379872;5978.910316345171;5979.4917432018765;5984.970075149597;6118.694065296806;6138.324784273637;6143.692815359124;6163.167972323679;5860.501857342174;5870.5010074144175;5895.380984996514;5897.3807750185615;5934.495567876606;5861.560382971209;6014;6017.217761690952;6028.3971602839365;6030.336662966983;5769.336526299361;5776.855578610568;5817.389174136824;5863.032599621185;5872.618279811799;5847.1229315603;5855.092460668603;5860.736266867916;5862.472377486047;5868.823935281948;5583.553315734813;5649.20911072442;5746.712664366849;5748.195252664714;5757.3954734753925;5829.8251052468495;5944.524438044859;5950;5950.9702451487465;5955.3746856581165;5761.740721667619;5769.826905192851;5769.9711501442225;5771.68255745938;5774.2782152230975 11:11:09 INFO - PROCESS | 15198 | |1;ext.html;598.6527813867957;638.7349250061225;641.4387411015362;647.2200997797654;657.5101549345738;34744.82627586862;38416.23167536648;38609.84170474887;43914.56085439145;44831.10337793242;47617.76191119048;48015.279770803376;48034.75982620082;48115.518844811544;48130.75934620331;9086.31903097929;9243.907560924372;9255.39839910407;9258.76853078675;9269.768255793626;9035.186833185013;9132.132447417485;9132.132447417485;9133.634654613808;9142.542872856364;9209.217216536603;9252.352335336529;9256.8611470828;9257.675981340655;9263.351565390392;9180.724578262629;9217.262618990499;9228.169464748138;9232.723018309462;9243.722688319362;8968.686095986674;9039.186473217442;9039.954800225956;9049.547522623843;9053.954730226307;42725.78637106826;43045.35431968523;43115.56884431147;43188.3521747174;43237;3653.6529029742132;3722.255548890206;3734.458503516975;3743;3761.097336639214;34001.82999084989;34330.82834585811;34613.307733845184;34738;34759.65240347589;4409.603135717769;4465.732056076613;4478;4510.684252102355;4542.545745425431;351940.2402987994;354270;354271;354327;356048;64262.67868660627;64506.354936450974;64535;64795.084221210156;64837;66446.66776666182;66491.33508664851;66519.33480665229;66637.66681166564;66883.66558167178;15871;15881.28534215963;16138.838611613968;16144.677106457804;16215.108169050816;12089.939550302191;12099.758004839854;12169.330686812133;12194.207376520499;12239.755204895851;18307.749351130886;19895.502612434804;19943.501412464804;20040.89979550093;20154.596908061758;6193.752249909953;7103.2541583133025;7151.177614574391;7218.494705370574;7305.561666300039;996.7059717383389;1044.2951008069522;1046.6232156423682;1102.9117670586334;1218.634409677111;33998.66001340005;34431.311373772885;34436.48345274823;34444.13889652779;34859.82570087133;1095.479647167589;1111.4998250787016;1136.6362763915633;1228.9078319126102;1234.6049264235537;17487.475375738748;18016;18052.09739513019;18056.90971545134;18244.726329105073;570751;572893;579407;580956;581819;61432.38567614356;61915.071273930924;61970.38029619736;62030.37969620336;62036.68981655063;1281.8718128187106;1374.6975665353607;1388.6458952966727;1397.2454874367932;1403.1791402029926;33085.83457082699;34477.82761086179;35216.47175292424;35241.295174096886;35246.590136394254;53058.73470632622;53097.4690253108;53560.19659705029;53626.19560706514;53643.19535207053;421098;424057;424329;426856;427466;26165.215043548724;28784.856075719486;29400.705992940653;29413;29966.70033299639;50202.49797501978;50945.74527127341;51032.234516483026;51033.23450148176;52731;42300.78849605732;42758.144837103704;42998.570014300705;43079;43877;39445.60554394419;42188;42955.7852210737;43184;44274.3358849624;43604.78197609119;44228.77885610551;44651;46409.76795116003;47653.28520072132;38073.23853522969;38894;39060.80469597634;39935.60064399319;39977.80011100042;28990.61265802714;29541;29639.110826674372;29747.55378669365;30111.548326775555 11:11:09 INFO - PROCESS | 15198 | |2;jquery.html;406.55076140864355;412.79979210083087;414.4301585320183;416.57717416821924;418.4622759753717;14099.859001409983;17721;19435.416937491864;19664.705029424553;19675.901620491895;13898.305084745747;16901.408450704228;16924.49226523203;18568.16443260053;19275.325363612275;5470.070088085035;5653.293338332708;5656.802011929583;5686.800961966332;5707.1153971134545;5590.105583106703;5610.607257491977;5617.831465056034;5629.831105066854;5666.263385759866;4022.235775202717;5864.941350586503;6160.84597885052;6171.382861713838;6279.623222606659;6163.3836616338;6194.070889366609;6204.968975155118;6263.498920086405;6286.277078136027;5404.32445944257;5503.9174412383845;5559.777608895639;5582.134769110794;5585.888282234332;23000.53998920029;23184.304470865733;23211.3036608902;23215.93721098801;23649.645255321186;2420.697412823397;2443.3280847766828;2508.2099138771227;2649.9274944875956;2933.9706602934125;9992.500374981222;10549.789004219872;14073.36669849849;14434.35045422948;15039.924800376037;1965.5184479802397;1978.3768113044346;1982.8810271383618;2013.32553594546;2045.6624656931738;80630;80869;81612.18387816106;82759;84021.57989210016;11107.83338249927;11267.267627604344;11299.322040677585;11302.321860688386;11438.08328876148;15699.764503532457;15791.210439478209;15816.446424375092;15836.129012904401;15837.445689400816;7918.881216781753;8068.3948703847445;8151.918480815235;8231.670733170726;8237.958810205912;7636;7652.579108148994;7699.730509432143;7793.220677932275;7821.5698136601895;4499.190145773727;4595.333676616872;4886.804527818847;4890.413150421901;5042.243663450512;1971.9211231550578;1985.5631761012555;2049.9590008199752;2062.6905964105504;2064.4838790302424;934.3506263147184;1029.5933106423072;1056.6196169379018;1117.6982214801958;1123.3035517978906;18651.253949842125;19339;19455.12451939666;19499.122539485757;19527.414177574694;1022.621629996891;1093.6445655162104;1093.6773651772746;1110.161827820007;1116.6873275482878;10338.638147664795;10965.177611679155;11031.117510599133;11063.336199828036;11069.72325691848;469822;473068;474084;485269;485864;91841.54079229561;96585.51707241559;97877.51061244648;98389.50805245928;98832.50583747035;1030.9639162629421;1046.1735229168871;1048.8164571200161;1049.1134990932649;1061.5010944855906;17152.828471715122;17155.742663860303;17310.913445432692;17348.132593370534;17472.30110795554;38548.229035418575;39269;40149.59850401458;41847.58152418437;42043;57807.421925781884;57813.4218657808;59504;59651.701741492754;59766;7723.266289702469;8024.2376974187355;8030.959845200737;8098.959505202436;8606.139386061344;9006.369554131148;9124.871625677988;9167.587458564247;12188.573399931314;13202.669933251746;11863.881361186277;12095.274283543016;12222.572209972608;12333.075019373762;12338.876611234133;5817.563682723728;8337.916620833714;9353.643721660457;11775.587854425054;12293.770622937634;6077.908831367621;9991.900080999098;10861.42509336126;12188.75622487563;12262.816057758962;8793.560321984003;9026.864597030919;9053.094690531158;9115.908840911772;9132.77168070777;9097.363184577016;11508.13688973347;12006.279623222284;12251.08116891219;12261.816072758736 11:11:09 INFO - PROCESS | 15198 | |3;mootools.html;1197.4072833947198;1492.5373134328356;1516.636007358234;1684.5199118251294;1826.5707558723698;8592.785180370485;8982.281417486602;9019.864702029476;9249.815003699921;9267.490288034156;9042.547872606376;9243.306751993594;9253.398529095604;9255.629774809016;9256.768580785467;2075.3255192062566;2333.731620863597;2371.8576885386847;2403.819713521484;2410.433548116191;2395.784379405853;2402.615581506959;2403.3030421177837;2405.458771776345;2407.109369533275;2396.772306630876;2453.3498622864954;2453.5338285725657;2453.5338285725657;2457.0908763757348;2060.4024832798546;2353.3410645019335;2424.187897054489;2425.8180636452335;2431.720352159489;2402.0752010475917;2420.9757902420924;2432.1122790181635;2437.500312435964;2472.320111969205;8941.374103812735;8952.462852228888;9038.276937844958;9040.819183616291;9042.23141033013;5336.30628018359;5449.073657478239;5462.262594549725;5474.315710536183;5475.06923822951;3691.280200360931;3715.702743780491;3771.302309072803;3801.6958643308467;3811.1424929390664;1143.5654451308533;1257.2079589858422;2170.0378982688676;2175.4126385875884;2179.4115588791096;12134.514619415213;12520.499180032879;12541.811872821823;12545.811812822814;12559.372031398394;4740.336352910595;4744.217204161275;4814.638902082358;4856.150173719585;4861.489543597942;11014.73330566979;11018.063464605439;11019.779604407868;11020.834687479693;11020.834687479693;4677.579017888406;5372.247885296062;5425.538829199562;5429.864253393618;5430.294061771944;4916.115099282163;5006.1739812930455;5017.84946451607;5041.924371134436;5052.520010598987;4076.7158345121193;4098.672106231495;4127.4522054229665;4193.874183774492;4201.894952626209;1259.1249081888;1282.39086433945;1285.2673975833873;1289.767841788487;1300.4928078049568;964.5707660091332;990.8563258327503;1065.7708592652616;1071.1430855315716;1076.6231818863334;6320.968395158086;6357.904631430532;6370.872582548323;6489.805305840832;6592.945128779373;465.24165610055394;468.9788959496831;471.40838247998545;472.0464661384054;480.64912613792126;4330.848420305274;4423.270160423558;4432.468103827561;4456.242438785414;4465.6427485801705;11287.774244515063;11523.53905843757;11559.248648837967;11606.651800446;11687.590934317259;10531.210159237935;10933.125349971982;11366.09071274296;11492.195546311845;11531.13516486267;8348.248657620843;8405.747827565061;8511.574421279036;8526.062133165347;8550.57247137653;6388.744450221939;6635.8009259722285;6660.933390666225;6707;6754.088198093391;10088.546015429176;10541.631042913461;10555.577776889146;10575.735606609896;10693.518791654238;10478.738031549272;10789.62236321725;10809.783804324026;10890.61882834097;10909.509072091616;7010.859782804418;7866.174051724715;7974.16271291506;8066.798330041561;8132.796680083045;8974.41666291689;8997.415168014059;9004.864927025968;9050.864237036581;9083.500407477652;7570.318671319607;7689.3848492121615;7829.45193836426;7860.960695196487;7863.685452582062;7782.143964163824;7998.160193179865;8029.638666259915;8116.75649730509;8124.22819832115;7508.361789247757;7526;7539.283768042027;7593.316601505891;7632.274933881273;5620.353659329229;5624.072028115315;5629.296337957755;5725.312962444366;5764.625299355528;5704.80033198836;5722.198892155185;5740.167675687086;5803.071508558611;5950.821475355746 11:11:09 INFO - PROCESS | 15198 | |4;prototype.html;342.03034397483134;343.4127641732637;350.59156083163106;352.75659794741625;354.04584644383397;5285.471452854717;5318.1490961446125;5327.547158491523;5343.251944727736;5367.369988705535;5249.401546234619;5296.390915044771;5296.973515132419;5299.8410047698535;5308.893822123555;3973.5231772187376;4057.57395473474;4169.791510424482;4181.393697913801;4210.326347784355;4152.626263636272;4186.58134186582;4187.225363307783;4188.769617671027;4202.852900148496;3969.8412063517426;4148.917021659565;4151.214977559648;4159.688023398242;4161.791910404483;4013.8595149169787;4149.481314835645;4160.8363650901665;4166.729162604421;4167.541570427251;3696.149885526317;3700.389435743099;3700.833462494194;3702.6855466309344;3703.870274592093;5065.822696205652;5083.8983220335385;5099;5101.7194054327;5107.846764597068;4442.222611043055;4687.507811679793;4700.1539722849875;4702.130105930414;4703.824043989002;5474.37044739856;5479.506844383985;5500.4499550045075;5519.448055194489;5561;3886.145048089416;3890.3580909149955;3911.765294082364;3917.4907262056036;3927.5483319418345;5530.972345138289;5687.601867869251;5697.6866272355;5712.942870571283;5737.913931291076;4012.27778999777;4012.337964235868;4021.617946295097;4039.777812220297;4049.8582549610624;4090.2433049885867;4090.3250963590676;4091.118085680561;4092;4110.917781644351;4512.52618475062;4687.695299805502;4697.201475749131;4704.647151463654;4707.81168753246;5500.972495137498;5502.724863756796;5519.613627046068;5520.558355331564;5536.723163841792;1397.4480080368198;1399.6011136825955;1403.5929580421564;1412.2938530734632;1416.2776983738368;896.914793094655;900.8873890763655;919.7240827751648;922.294444749767;942.8632848237104;5234.581233501387;5262.368515778055;5262.868428289246;5323.22813192085;5350.625456218105;5685.374608793029;5690.800821971211;5695.345035320991;5697.401772813795;5704.629199102128;5128.948710512922;5330.360356757214;5331.1470164773455;5345.22494238341;5351.411344752074;3028.545718142252;3034.5903303054247;3034.620672415948;3037.635483741921;3047.9542806857917;5637.7181140942785;5734.655920644691;5801.361850196475;5878.970605147032;5883;5628.324601047819;5673.489385955284;5687.43125687428;5693.117566777156;5726.341470730836;5733.283339582552;5744.425557444222;5747.339056008529;5770.336411312753;5792.768289268466;5445.727713614224;5585.413531579125;5590.944090559043;5658.575606829587;5664.5751568631695;5593.748281327432;5595.5523558116065;5609.27079479665;5627.606067575231;5637.46444087811;5486.670799752028;5801.912971305518;5835.44563266489;5835.94164058371;5847;3997.2405243003736;4013.41805438204;4020.8592699255387;4025.0138716013594;4037.454943582696;5584.636998595079;5610.242617246619;5635.633683810539;5645.520130788929;5658.264425624807;5317.8404647860625;5464.80873169453;5499.4225606310765;5499.50504454601;5502.284702988746;5362.839114826562;5486.506214440719;5487.890242195213;5507.917381239365;5511.834644960658;5226.700251889157;5403.540699040625;5415.106507426231;5433.402325744086;5439.156930675752;5136.717480538607;5544.279243698294;5550.222968784293;5552.33371995347;5609.411011843861;5451.100568406168;5471.37079235893;5480.383286930445;5492.945070549244;5508.614396992172 11:11:09 INFO - PROCESS | 15198 | |5;yui.html;735.793977686248;780.2743448592809;845.3533047218875;846.7459762071377;874.5933141089388;13921.721565568683;14658.63353416165;14773.852261477383;15015.399384024626;15026.173560454185;14273.857261427383;14804.703905921875;14816.703665926674;14829.110253384777;14835.109893406403;712.1881055596624;835.5362773660615;854.9871751923727;855.1918437076964;861.1431625532604;862.2670729879615;863.1411745313422;865.074370423647;865.4547634989948;865.5974971638187;738.9852202955938;885.1812073831712;886.6276164011131;887.6937456577502;888.0276097673037;874.7375787263795;882.100257737111;883.3639779358851;891.0243283604443;893.6380765789866;647.3526473526474;648.6205569741707;648.870225954811;649.4025496543192;650.1645385679426;14102.224377659328;14124.717505649933;14127.78808317876;14129.293535323295;14149.221792801392;13626.863731362659;13842.723145537133;13887.58337249884;14043.859561404357;14324.85675143256;12453.688657783538;12664;12711.872881271162;12733.299668518228;12760.170588911691;4512.075024619978;4519.525449827786;4553.749543775091;4554.863354099382;4560.247559152735;57842.710786446216;58081.70959145219;60030.39969600292;60030.399696003355;60259.69870150621;10800.92173730483;10829.783404331869;10886.455677216107;11351.148663850245;11380.487878045507;19754.90122549378;19949.900250498944;19953.501162471053;20007.59984800325;20020.69968950467;8026.839463210703;8089;8114.918850811417;8115.269625733712;8235.95882020586;4534.7052441591195;4627.3521706961055;4732.1009008289;4739.099571081483;4771.71369717818;3373.39278929795;3403.1832360233366;3440.8107554084263;3447.1554469155217;3449.586049674055;658.8122385120314;661.7121552124842;661.7797290091769;662.460095022555;675.7161991963387;649.4966401039233;651.0104640945736;714.6462501062008;746.8431629357787;761.4859969520552;10173.54219060144;10471;10535.104516116216;10622.94688526568;10624.681259562225;673.4948788408693;680.4760334542474;720.0027961273569;766.9769906902802;795.9056297590813;4984.850454486371;5063.8987220255385;5105.566026887682;5112.795488180505;5123.699606637197;75701.62149189219;77075;78266.6086669563;78852;79204.6039769809;67686;68689;68792;69751.3024869755;71022.64488677627;61602;61870.0719489208;62234;62262;62689.37310626927;9563.426194428079;9771.462569558742;9843.753906152117;9886.85169722469;9929;41183.382249265684;41400;41529.58470415378;41784;41985.95035124147;53404.46595533995;55535.72232138814;55968.72015639896;55990.440095598526;56016.71991640016;11589.188756786943;12179.208351457128;12495;12637.115401921777;12866.742665146832;10799.514021868878;13578;14098.50655227062;14328.426862925366;14528.854711453174;13679.24764137982;14137.081089729134;14165.008449408442;14259.500917467838;14269.072510286798;12923.483060677467;13409.994250431062;13619.25094119833;13892.93053534726;14084;13503.189808611514;13695.452181912611;13760;13871.167729936236;14228.35972381266;10968.396738179477;11000.944995274973;11200.887991120311;11266.66200014001;11795.941020294844;11496.252743571642;11654.825177622512;11821.881781182077;11984.28094314344;11997.400129993292 11:11:09 INFO - PROCESS | 15198 | __end_tp_report 11:11:09 INFO - PROCESS | 15198 | __start_cc_report 11:11:09 INFO - PROCESS | 15198 | _x_x_mozilla_cycle_collect,859 11:11:09 INFO - PROCESS | 15198 | __end_cc_report 11:11:09 INFO - PROCESS | 15198 | __startTimestamp1463767869084__endTimestamp 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | ------- Summary: start ------- 11:11:09 INFO - PROCESS | 15198 | Number of tests: 6 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#0] dojo.html Cycles:165 Average:5528.64 Median:5769.34 stddev:641.90 (11.1%) stddev-sans-first:616.86 11:11:09 INFO - PROCESS | 15198 | Values: 3180.0 3182.7 3193.0 3205.8 3261.9 5407.5 5467.2 5559.3 5598.5 5629.1 5499.1 5536.4 5563.6 5614.8 5618.9 4965.7 5056.9 5077.3 5092.3 5219.5 5130.0 5143.9 5185.1 5200.1 5214.9 4922.9 4986.3 5033.9 5034.3 5071.0 5046.1 5063.6 5063.7 5068.7 5103.7 4907.4 5023.3 5026.6 5047.3 5059.6 5459.5 5490.6 5520.9 5600.0 5621.0 6004.0 6013.8 6016.5 6044.3 6061.6 5836.2 5982.7 5998.3 5999.2 6000.8 5505.6 5540.7 5549.2 5644.0 5647.5 6110.8 6132.4 6132.5 6133.7 6136.9 5668.3 5791.0 5954.3 5961.4 5967.1 5845.3 5984.1 5985.4 5987.8 6040.9 5908.7 5912.1 5954.8 5960.2 5962.0 5798.2 5897.6 5945.9 5953.3 5962.6 4545.6 4548.2 4553.3 4584.8 4589.4 4015.4 4024.2 4033.2 4033.6 4063.3 5467.2 5509.7 5541.0 5572.0 5726.7 5942.2 5945.3 5956.6 5974.3 6015.1 5617.0 5618.2 5624.0 5626.4 5626.5 4816.6 4873.9 4902.6 4921.0 4941.0 6166.3 6189.0 6192.7 6194.0 6201.7 5963.0 5973.7 5977.8 5978.9 5979.5 5985.0 6118.7 6138.3 6143.7 6163.2 5860.5 5870.5 5895.4 5897.4 5934.5 5861.6 6014.0 6017.2 6028.4 6030.3 5769.3 5776.9 5817.4 5863.0 5872.6 5847.1 5855.1 5860.7 5862.5 5868.8 5583.6 5649.2 5746.7 5748.2 5757.4 5829.8 5944.5 5950.0 5951.0 5955.4 5761.7 5769.8 5770.0 5771.7 5774.3 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#1] ext.html Cycles:180 Average:62440.66 Median:43097.28 stddev:122415.46 (284.0%) stddev-sans-first:122670.81 11:11:09 INFO - PROCESS | 15198 | Values: 598.7 638.7 641.4 647.2 657.5 34744.8 38416.2 38609.8 43914.6 44831.1 47617.8 48015.3 48034.8 48115.5 48130.8 9086.3 9243.9 9255.4 9258.8 9269.8 9035.2 9132.1 9132.1 9133.6 9142.5 9209.2 9252.4 9256.9 9257.7 9263.4 9180.7 9217.3 9228.2 9232.7 9243.7 8968.7 9039.2 9040.0 9049.5 9054.0 42725.8 43045.4 43115.6 43188.4 43237.0 3653.7 3722.3 3734.5 3743.0 3761.1 34001.8 34330.8 34613.3 34738.0 34759.7 4409.6 4465.7 4478.0 4510.7 4542.5 351940.2 354270.0 354271.0 354327.0 356048.0 64262.7 64506.4 64535.0 64795.1 64837.0 66446.7 66491.3 66519.3 66637.7 66883.7 15871.0 15881.3 16138.8 16144.7 16215.1 12089.9 12099.8 12169.3 12194.2 12239.8 18307.7 19895.5 19943.5 20040.9 20154.6 6193.8 7103.3 7151.2 7218.5 7305.6 996.7 1044.3 1046.6 1102.9 1218.6 33998.7 34431.3 34436.5 34444.1 34859.8 1095.5 1111.5 1136.6 1228.9 1234.6 17487.5 18016.0 18052.1 18056.9 18244.7 570751.0 572893.0 579407.0 580956.0 581819.0 61432.4 61915.1 61970.4 62030.4 62036.7 1281.9 1374.7 1388.6 1397.2 1403.2 33085.8 34477.8 35216.5 35241.3 35246.6 53058.7 53097.5 53560.2 53626.2 53643.2 421098.0 424057.0 424329.0 426856.0 427466.0 26165.2 28784.9 29400.7 29413.0 29966.7 50202.5 50945.7 51032.2 51033.2 52731.0 42300.8 42758.1 42998.6 43079.0 43877.0 39445.6 42188.0 42955.8 43184.0 44274.3 43604.8 44228.8 44651.0 46409.8 47653.3 38073.2 38894.0 39060.8 39935.6 39977.8 28990.6 29541.0 29639.1 29747.6 30111.5 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#2] jquery.html Cycles:180 Average:28667.75 Median:39709.30 stddev:78986.24 (198.9%) stddev-sans-first:79179.31 11:11:09 INFO - PROCESS | 15198 | Values: 406.6 412.8 414.4 416.6 418.5 14099.9 17721.0 19435.4 19664.7 19675.9 13898.3 16901.4 16924.5 18568.2 19275.3 5470.1 5653.3 5656.8 5686.8 5707.1 5590.1 5610.6 5617.8 5629.8 5666.3 4022.2 5864.9 6160.8 6171.4 6279.6 6163.4 6194.1 6205.0 6263.5 6286.3 5404.3 5503.9 5559.8 5582.1 5585.9 23000.5 23184.3 23211.3 23215.9 23649.6 2420.7 2443.3 2508.2 2649.9 2934.0 9992.5 10549.8 14073.4 14434.4 15039.9 1965.5 1978.4 1982.9 2013.3 2045.7 80630.0 80869.0 81612.2 82759.0 84021.6 11107.8 11267.3 11299.3 11302.3 11438.1 15699.8 15791.2 15816.4 15836.1 15837.4 7918.9 8068.4 8151.9 8231.7 8238.0 7636.0 7652.6 7699.7 7793.2 7821.6 4499.2 4595.3 4886.8 4890.4 5042.2 1971.9 1985.6 2050.0 2062.7 2064.5 934.4 1029.6 1056.6 1117.7 1123.3 18651.3 19339.0 19455.1 19499.1 19527.4 1022.6 1093.6 1093.7 1110.2 1116.7 10338.6 10965.2 11031.1 11063.3 11069.7 469822.0 473068.0 474084.0 485269.0 485864.0 91841.5 96585.5 97877.5 98389.5 98832.5 1031.0 1046.2 1048.8 1049.1 1061.5 17152.8 17155.7 17310.9 17348.1 17472.3 38548.2 39269.0 40149.6 41847.6 42043.0 57807.4 57813.4 59504.0 59651.7 59766.0 7723.3 8024.2 8031.0 8099.0 8606.1 9006.4 9124.9 9167.6 12188.6 13202.7 11863.9 12095.3 12222.6 12333.1 12338.9 5817.6 8337.9 9353.6 11775.6 12293.8 6077.9 9991.9 10861.4 12188.8 12262.8 8793.6 9026.9 9053.1 9115.9 9132.8 9097.4 11508.1 12006.3 12251.1 12261.8 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#3] mootools.html Cycles:180 Average:6075.63 Median:470.19 stddev:3424.86 (728.4%) stddev-sans-first:3414.84 11:11:09 INFO - PROCESS | 15198 | Values: 1197.4 1492.5 1516.6 1684.5 1826.6 8592.8 8982.3 9019.9 9249.8 9267.5 9042.5 9243.3 9253.4 9255.6 9256.8 2075.3 2333.7 2371.9 2403.8 2410.4 2395.8 2402.6 2403.3 2405.5 2407.1 2396.8 2453.3 2453.5 2453.5 2457.1 2060.4 2353.3 2424.2 2425.8 2431.7 2402.1 2421.0 2432.1 2437.5 2472.3 8941.4 8952.5 9038.3 9040.8 9042.2 5336.3 5449.1 5462.3 5474.3 5475.1 3691.3 3715.7 3771.3 3801.7 3811.1 1143.6 1257.2 2170.0 2175.4 2179.4 12134.5 12520.5 12541.8 12545.8 12559.4 4740.3 4744.2 4814.6 4856.2 4861.5 11014.7 11018.1 11019.8 11020.8 11020.8 4677.6 5372.2 5425.5 5429.9 5430.3 4916.1 5006.2 5017.8 5041.9 5052.5 4076.7 4098.7 4127.5 4193.9 4201.9 1259.1 1282.4 1285.3 1289.8 1300.5 964.6 990.9 1065.8 1071.1 1076.6 6321.0 6357.9 6370.9 6489.8 6592.9 465.2 469.0 471.4 472.0 480.6 4330.8 4423.3 4432.5 4456.2 4465.6 11287.8 11523.5 11559.2 11606.7 11687.6 10531.2 10933.1 11366.1 11492.2 11531.1 8348.2 8405.7 8511.6 8526.1 8550.6 6388.7 6635.8 6660.9 6707.0 6754.1 10088.5 10541.6 10555.6 10575.7 10693.5 10478.7 10789.6 10809.8 10890.6 10909.5 7010.9 7866.2 7974.2 8066.8 8132.8 8974.4 8997.4 9004.9 9050.9 9083.5 7570.3 7689.4 7829.5 7861.0 7863.7 7782.1 7998.2 8029.6 8116.8 8124.2 7508.4 7526.0 7539.3 7593.3 7632.3 5620.4 5624.1 5629.3 5725.3 5764.6 5704.8 5722.2 5740.2 5803.1 5950.8 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#4] prototype.html Cycles:180 Average:4641.41 Median:5328.95 stddev:1354.01 (25.4%) stddev-sans-first:1318.80 11:11:09 INFO - PROCESS | 15198 | Values: 342.0 343.4 350.6 352.8 354.0 5285.5 5318.1 5327.5 5343.3 5367.4 5249.4 5296.4 5297.0 5299.8 5308.9 3973.5 4057.6 4169.8 4181.4 4210.3 4152.6 4186.6 4187.2 4188.8 4202.9 3969.8 4148.9 4151.2 4159.7 4161.8 4013.9 4149.5 4160.8 4166.7 4167.5 3696.1 3700.4 3700.8 3702.7 3703.9 5065.8 5083.9 5099.0 5101.7 5107.8 4442.2 4687.5 4700.2 4702.1 4703.8 5474.4 5479.5 5500.4 5519.4 5561.0 3886.1 3890.4 3911.8 3917.5 3927.5 5531.0 5687.6 5697.7 5712.9 5737.9 4012.3 4012.3 4021.6 4039.8 4049.9 4090.2 4090.3 4091.1 4092.0 4110.9 4512.5 4687.7 4697.2 4704.6 4707.8 5501.0 5502.7 5519.6 5520.6 5536.7 1397.4 1399.6 1403.6 1412.3 1416.3 896.9 900.9 919.7 922.3 942.9 5234.6 5262.4 5262.9 5323.2 5350.6 5685.4 5690.8 5695.3 5697.4 5704.6 5128.9 5330.4 5331.1 5345.2 5351.4 3028.5 3034.6 3034.6 3037.6 3048.0 5637.7 5734.7 5801.4 5879.0 5883.0 5628.3 5673.5 5687.4 5693.1 5726.3 5733.3 5744.4 5747.3 5770.3 5792.8 5445.7 5585.4 5590.9 5658.6 5664.6 5593.7 5595.6 5609.3 5627.6 5637.5 5486.7 5801.9 5835.4 5835.9 5847.0 3997.2 4013.4 4020.9 4025.0 4037.5 5584.6 5610.2 5635.6 5645.5 5658.3 5317.8 5464.8 5499.4 5499.5 5502.3 5362.8 5486.5 5487.9 5507.9 5511.8 5226.7 5403.5 5415.1 5433.4 5439.2 5136.7 5544.3 5550.2 5552.3 5609.4 5451.1 5471.4 5480.4 5492.9 5508.6 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - PROCESS | 15198 | [#5] yui.html Cycles:180 Average:16942.64 Median:4593.80 stddev:20955.43 (456.2%) stddev-sans-first:20978.87 11:11:09 INFO - PROCESS | 15198 | Values: 735.8 780.3 845.4 846.7 874.6 13921.7 14658.6 14773.9 15015.4 15026.2 14273.9 14804.7 14816.7 14829.1 14835.1 712.2 835.5 855.0 855.2 861.1 862.3 863.1 865.1 865.5 865.6 739.0 885.2 886.6 887.7 888.0 874.7 882.1 883.4 891.0 893.6 647.4 648.6 648.9 649.4 650.2 14102.2 14124.7 14127.8 14129.3 14149.2 13626.9 13842.7 13887.6 14043.9 14324.9 12453.7 12664.0 12711.9 12733.3 12760.2 4512.1 4519.5 4553.7 4554.9 4560.2 57842.7 58081.7 60030.4 60030.4 60259.7 10800.9 10829.8 10886.5 11351.1 11380.5 19754.9 19949.9 19953.5 20007.6 20020.7 8026.8 8089.0 8114.9 8115.3 8236.0 4534.7 4627.4 4732.1 4739.1 4771.7 3373.4 3403.2 3440.8 3447.2 3449.6 658.8 661.7 661.8 662.5 675.7 649.5 651.0 714.6 746.8 761.5 10173.5 10471.0 10535.1 10622.9 10624.7 673.5 680.5 720.0 767.0 795.9 4984.9 5063.9 5105.6 5112.8 5123.7 75701.6 77075.0 78266.6 78852.0 79204.6 67686.0 68689.0 68792.0 69751.3 71022.6 61602.0 61870.1 62234.0 62262.0 62689.4 9563.4 9771.5 9843.8 9886.9 9929.0 41183.4 41400.0 41529.6 41784.0 41986.0 53404.5 55535.7 55968.7 55990.4 56016.7 11589.2 12179.2 12495.0 12637.1 12866.7 10799.5 13578.0 14098.5 14328.4 14528.9 13679.2 14137.1 14165.0 14259.5 14269.1 12923.5 13410.0 13619.3 13892.9 14084.0 13503.2 13695.5 13760.0 13871.2 14228.4 10968.4 11000.9 11200.9 11266.7 11795.9 11496.3 11654.8 11821.9 11984.3 11997.4 11:11:09 INFO - PROCESS | 15198 | -------- Summary: end -------- 11:11:09 INFO - PROCESS | 15198 | 11:11:09 INFO - TEST-INFO | 15198: exit 0 11:11:09 INFO - TEST-OK | dromaeo_css | took 1112958ms 11:11:09 INFO - TEST-START | kraken 11:11:09 INFO - operating with platform_type : linux_ 11:11:09 INFO - Initialising browser for kraken test... 11:11:09 INFO - TEST-INFO | started process 15385 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprLSqj5/profile http://localhost:41926/getInfo.html) 11:11:13 INFO - PROCESS | 15385 | __metrics Screen width/height:1600/1200 11:11:13 INFO - PROCESS | 15385 | colorDepth:24 11:11:13 INFO - PROCESS | 15385 | Browser inner width/height: 1024/697 11:11:13 INFO - PROCESS | 15385 | __metrics 11:11:14 ERROR - PROCESS | 15385 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:11:15 INFO - TEST-INFO | 15385: exit 0 11:11:15 INFO - Browser initialized. 11:11:15 INFO - Running cycle 1/1 for kraken test... 11:11:15 INFO - TEST-INFO | started process 15467 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprLSqj5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:27 INFO - PROCESS | 15467 | RSS: Main: 153088000 11:11:27 INFO - PROCESS | 15467 | 11:11:30 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41926/tests/kraken/driver.html?testName=audio-beat-detection) 11:11:30 INFO - PROCESS | 15467 | RSS: Main: 208900096 11:11:30 INFO - PROCESS | 15467 | 11:11:33 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41926/tests/kraken/driver.html?testName=audio-dft) 11:11:34 INFO - PROCESS | 15467 | RSS: Main: 198107136 11:11:34 INFO - PROCESS | 15467 | 11:11:37 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41926/tests/kraken/driver.html?testName=audio-fft) 11:11:38 INFO - PROCESS | 15467 | RSS: Main: 265142272 11:11:38 INFO - PROCESS | 15467 | 11:11:40 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41926/tests/kraken/driver.html?testName=audio-oscillator) 11:11:40 INFO - PROCESS | 15467 | RSS: Main: 174542848 11:11:40 INFO - PROCESS | 15467 | 11:11:43 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41926/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:11:43 INFO - PROCESS | 15467 | RSS: Main: 178769920 11:11:43 INFO - PROCESS | 15467 | 11:11:46 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41926/tests/kraken/driver.html?testName=imaging-darkroom) 11:11:47 INFO - PROCESS | 15467 | RSS: Main: 215035904 11:11:47 INFO - PROCESS | 15467 | 11:11:51 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41926/tests/kraken/driver.html?testName=imaging-desaturate) 11:11:51 INFO - PROCESS | 15467 | RSS: Main: 212869120 11:11:51 INFO - PROCESS | 15467 | 11:11:54 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41926/tests/kraken/driver.html?testName=json-parse-financial) 11:11:55 INFO - PROCESS | 15467 | RSS: Main: 214208512 11:11:55 INFO - PROCESS | 15467 | 11:11:56 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41926/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:11:57 INFO - PROCESS | 15467 | RSS: Main: 202993664 11:11:57 INFO - PROCESS | 15467 | 11:11:59 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:11:59 INFO - PROCESS | 15467 | RSS: Main: 206196736 11:11:59 INFO - PROCESS | 15467 | 11:12:01 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:12:02 INFO - PROCESS | 15467 | RSS: Main: 217100288 11:12:02 INFO - PROCESS | 15467 | 11:12:05 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:12:05 INFO - PROCESS | 15467 | RSS: Main: 224006144 11:12:05 INFO - PROCESS | 15467 | 11:12:08 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:12:09 INFO - PROCESS | 15467 | RSS: Main: 220004352 11:12:09 INFO - PROCESS | 15467 | 11:12:10 INFO - PROCESS | 15467 | Cycle 1(1): loaded http://localhost:41926/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41926/tests/kraken/driver.html?testName=ai-astar) 11:12:11 INFO - PROCESS | 15467 | RSS: Main: 265801728 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | __start_tp_report 11:12:11 INFO - PROCESS | 15467 | _x_x_mozilla_page_load 11:12:11 INFO - PROCESS | 15467 | _x_x_mozilla_page_load_details 11:12:11 INFO - PROCESS | 15467 | |i|pagename|runs| 11:12:11 INFO - PROCESS | 15467 | |0;ai-astar;111;106;110;109;109;110;111;110;111;110 11:12:11 INFO - PROCESS | 15467 | |1;audio-beat-detection;152;130;149;222;125;151;150;129;183;130 11:12:11 INFO - PROCESS | 15467 | |2;audio-dft;162;162;163;161;161;160;161;161;162;162 11:12:11 INFO - PROCESS | 15467 | |3;audio-fft;75;75;75;156;75;73;75;73;129;74 11:12:11 INFO - PROCESS | 15467 | |4;audio-oscillator;91;92;141;92;92;92;124;92;91;124 11:12:11 INFO - PROCESS | 15467 | |5;imaging-gaussian-blur;116;117;116;116;117;117;116;116;116;117 11:12:11 INFO - PROCESS | 15467 | |6;imaging-darkroom;145;145;145;145;145;144;145;145;145;145 11:12:11 INFO - PROCESS | 15467 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;98;96 11:12:11 INFO - PROCESS | 15467 | |8;json-parse-financial;56;53;54;53;54;53;55;113;51;51 11:12:11 INFO - PROCESS | 15467 | |9;json-stringify-tinderbox;57;58;58;57;59;59;58;59;59;59 11:12:11 INFO - PROCESS | 15467 | |10;stanford-crypto-aes;85;77;78;77;83;78;80;84;78;84 11:12:11 INFO - PROCESS | 15467 | |11;stanford-crypto-ccm;122;122;118;119;119;116;116;119;203;119 11:12:11 INFO - PROCESS | 15467 | |12;stanford-crypto-pbkdf2;168;163;159;165;165;162;160;159;159;160 11:12:11 INFO - PROCESS | 15467 | |13;stanford-crypto-sha256-iterative;65;66;63;69;63;61;63;62;62;61 11:12:11 INFO - PROCESS | 15467 | __end_tp_report 11:12:11 INFO - PROCESS | 15467 | __start_cc_report 11:12:11 INFO - PROCESS | 15467 | _x_x_mozilla_cycle_collect,1830 11:12:11 INFO - PROCESS | 15467 | __end_cc_report 11:12:11 INFO - PROCESS | 15467 | __startTimestamp1463767931186__endTimestamp 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | ------- Summary: start ------- 11:12:11 INFO - PROCESS | 15467 | Number of tests: 14 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.49 (1.4%) stddev-sans-first:1.51 11:12:11 INFO - PROCESS | 15467 | Values: 111.0 106.0 110.0 109.0 109.0 110.0 111.0 110.0 111.0 110.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#1] audio-beat-detection Cycles:10 Average:152.10 Median:150.50 stddev:30.00 (19.9%) stddev-sans-first:31.82 11:12:11 INFO - PROCESS | 15467 | Values: 152.0 130.0 149.0 222.0 125.0 151.0 150.0 129.0 183.0 130.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 11:12:11 INFO - PROCESS | 15467 | Values: 162.0 162.0 163.0 161.0 161.0 160.0 161.0 161.0 162.0 162.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#3] audio-fft Cycles:10 Average:88.00 Median:75.00 stddev:29.43 (39.2%) stddev-sans-first:30.84 11:12:11 INFO - PROCESS | 15467 | Values: 75.0 75.0 75.0 156.0 75.0 73.0 75.0 73.0 129.0 74.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:92.00 stddev:18.91 (20.6%) stddev-sans-first:19.55 11:12:11 INFO - PROCESS | 15467 | Values: 91.0 92.0 141.0 92.0 92.0 92.0 124.0 92.0 91.0 124.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 11:12:11 INFO - PROCESS | 15467 | Values: 116.0 117.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 11:12:11 INFO - PROCESS | 15467 | Values: 145.0 145.0 145.0 145.0 145.0 144.0 145.0 145.0 145.0 145.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.14 (1.2%) stddev-sans-first:1.20 11:12:11 INFO - PROCESS | 15467 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 98.0 96.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:53.50 stddev:18.93 (35.4%) stddev-sans-first:20.04 11:12:11 INFO - PROCESS | 15467 | Values: 56.0 53.0 54.0 53.0 54.0 53.0 55.0 113.0 51.0 51.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.73 11:12:11 INFO - PROCESS | 15467 | Values: 57.0 58.0 58.0 57.0 59.0 59.0 58.0 59.0 59.0 59.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:81.50 stddev:3.24 (4.0%) stddev-sans-first:2.98 11:12:11 INFO - PROCESS | 15467 | Values: 85.0 77.0 78.0 77.0 83.0 78.0 80.0 84.0 78.0 84.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:119.00 stddev:26.68 (22.4%) stddev-sans-first:28.22 11:12:11 INFO - PROCESS | 15467 | Values: 122.0 122.0 118.0 119.0 119.0 116.0 116.0 119.0 203.0 119.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.00 Median:162.50 stddev:3.16 (1.9%) stddev-sans-first:2.50 11:12:11 INFO - PROCESS | 15467 | Values: 168.0 163.0 159.0 165.0 165.0 162.0 160.0 159.0 159.0 160.0 11:12:11 INFO - PROCESS | 15467 | 11:12:11 INFO - PROCESS | 15467 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:2.51 (4.0%) stddev-sans-first:2.60 11:12:11 INFO - PROCESS | 15467 | Values: 65.0 66.0 63.0 69.0 63.0 61.0 63.0 62.0 62.0 61.0 11:12:11 INFO - PROCESS | 15467 | -------- Summary: end -------- 11:12:11 INFO - PROCESS | 15467 | 11:12:12 INFO - TEST-INFO | 15467: exit 0 11:12:12 INFO - TEST-OK | kraken | took 62440ms 11:12:12 INFO - SUITE-END | took 1175s 11:12:12 INFO - Completed test suite (00:19:35) 11:12:12 INFO - javascript benchmark 11:12:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.9841000795, 3182.6817318268177, 3192.9999999999986, 3205.759568032398, 3261.8695252189914, 5407.459254074591, 5467.20725494803, 5559.305086864142, 5598.4961353478175, 5629.099344104944, 5499.065158922983, 5536.4186760390185, 5563.610547261683, 5614.831555053344, 5618.943810561904, 4965.726885021331, 5056.949430505703, 5077.289179514853, 5092.28707980883, 5219.478052194769, 5130.0, 5143.871403214912, 5185.066687996159, 5200.089984252741, 5214.895702085956, 4922.8523144305545, 4986.252062190664, 5033.899322013557, 5034.270030845507, 5071.0, 5046.142155833517, 5063.620228482879, 5063.670861394035, 5068.6705364151585, 5103.744812759347, 4907.411110666707, 5023.32185155003, 5026.648134630577, 5047.343845300087, 5059.62052846038, 5459.535939445152, 5490.6431081979545, 5520.861978450531, 5600.0, 5621.044422448153, 6004.0, 6013.830820139914, 6016.458518733335, 6044.304904935901, 6061.605995610315, 5836.241288632493, 5982.73077711504, 5998.25021872266, 5999.190109335227, 6000.789972350947, 5505.587080968903, 5540.722963851791, 5549.1676248562635, 5644.012297847901, 5647.463490968352, 6110.757479312526, 6132.41742034506, 6132.50939924805, 6133.723982420802, 6136.9079463808075, 5668.263125793703, 5790.971045144747, 5954.285485741739, 5961.433663801932, 5967.1347654589845, 5845.269341332333, 5984.132300816357, 5985.40145985398, 5987.820365389045, 6040.939590604125, 5908.675022873783, 5912.083627037816, 5954.76180952757, 5960.195373624505, 5961.970190149022, 5798.246227990418, 5897.557683173779, 5945.881082378328, 5953.315368732651, 5962.55280853929, 4545.6136228420955, 4548.158590660738, 4553.317002449659, 4584.839530616412, 4589.426321709786, 4015.377616469452, 4024.215278020758, 4033.153037862023, 4033.648519057708, 4063.2889244382113, 5467.234587157722, 5509.724513774296, 5541.030319694038, 5572.0, 5726.713664316851, 5942.168096466498, 5945.346011938684, 5956.5532585055425, 5974.2830860297045, 6015.1278064681255, 5616.971915140398, 5618.185363122325, 5624.0439125348785, 5626.409227031103, 5626.521745651666, 4816.590589799836, 4873.902521949542, 4902.558769710672, 4921.040397122527, 4940.950590494121, 6166.260048794084, 6188.9690551546955, 6192.690365481798, 6194.0, 6201.720922558496, 5963.0, 5973.731182096729, 5977.820665379872, 5978.910316345171, 5979.4917432018765, 5984.970075149597, 6118.694065296806, 6138.324784273637, 6143.692815359124, 6163.167972323679, 5860.501857342174, 5870.5010074144175, 5895.380984996514, 5897.3807750185615, 5934.495567876606, 5861.560382971209, 6014.0, 6017.217761690952, 6028.3971602839365, 6030.336662966983, 5769.336526299361, 5776.855578610568, 5817.389174136824, 5863.032599621185, 5872.618279811799, 5847.1229315603, 5855.092460668603, 5860.736266867916, 5862.472377486047, 5868.823935281948, 5583.553315734813, 5649.20911072442, 5746.712664366849, 5748.195252664714, 5757.3954734753925, 5829.8251052468495, 5944.524438044859, 5950.0, 5950.9702451487465, 5955.3746856581165, 5761.740721667619, 5769.826905192851, 5769.9711501442225, 5771.68255745938, 5774.2782152230975], "value": 5484.389832260356, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.6527813867957, 638.7349250061225, 641.4387411015362, 647.2200997797654, 657.5101549345738, 34744.82627586862, 38416.23167536648, 38609.84170474887, 43914.56085439145, 44831.10337793242, 47617.76191119048, 48015.279770803376, 48034.75982620082, 48115.518844811544, 48130.75934620331, 9086.31903097929, 9243.907560924372, 9255.39839910407, 9258.76853078675, 9269.768255793626, 9035.186833185013, 9132.132447417485, 9132.132447417485, 9133.634654613808, 9142.542872856364, 9209.217216536603, 9252.352335336529, 9256.8611470828, 9257.675981340655, 9263.351565390392, 9180.724578262629, 9217.262618990499, 9228.169464748138, 9232.723018309462, 9243.722688319362, 8968.686095986674, 9039.186473217442, 9039.954800225956, 9049.547522623843, 9053.954730226307, 42725.78637106826, 43045.35431968523, 43115.56884431147, 43188.3521747174, 43237.0, 3653.6529029742132, 3722.255548890206, 3734.458503516975, 3743.0, 3761.097336639214, 34001.82999084989, 34330.82834585811, 34613.307733845184, 34738.0, 34759.65240347589, 4409.603135717769, 4465.732056076613, 4478.0, 4510.684252102355, 4542.545745425431, 351940.2402987994, 354270.0, 354271.0, 354327.0, 356048.0, 64262.67868660627, 64506.354936450974, 64535.0, 64795.084221210156, 64837.0, 66446.66776666182, 66491.33508664851, 66519.33480665229, 66637.66681166564, 66883.66558167178, 15871.0, 15881.28534215963, 16138.838611613968, 16144.677106457804, 16215.108169050816, 12089.939550302191, 12099.758004839854, 12169.330686812133, 12194.207376520499, 12239.755204895851, 18307.749351130886, 19895.502612434804, 19943.501412464804, 20040.89979550093, 20154.596908061758, 6193.752249909953, 7103.2541583133025, 7151.177614574391, 7218.494705370574, 7305.561666300039, 996.7059717383389, 1044.2951008069522, 1046.6232156423682, 1102.9117670586334, 1218.634409677111, 33998.66001340005, 34431.311373772885, 34436.48345274823, 34444.13889652779, 34859.82570087133, 1095.479647167589, 1111.4998250787016, 1136.6362763915633, 1228.9078319126102, 1234.6049264235537, 17487.475375738748, 18016.0, 18052.09739513019, 18056.90971545134, 18244.726329105073, 570751.0, 572893.0, 579407.0, 580956.0, 581819.0, 61432.38567614356, 61915.071273930924, 61970.38029619736, 62030.37969620336, 62036.68981655063, 1281.8718128187106, 1374.6975665353607, 1388.6458952966727, 1397.2454874367932, 1403.1791402029926, 33085.83457082699, 34477.82761086179, 35216.47175292424, 35241.295174096886, 35246.590136394254, 53058.73470632622, 53097.4690253108, 53560.19659705029, 53626.19560706514, 53643.19535207053, 421098.0, 424057.0, 424329.0, 426856.0, 427466.0, 26165.215043548724, 28784.856075719486, 29400.705992940653, 29413.0, 29966.70033299639, 50202.49797501978, 50945.74527127341, 51032.234516483026, 51033.23450148176, 52731.0, 42300.78849605732, 42758.144837103704, 42998.570014300705, 43079.0, 43877.0, 39445.60554394419, 42188.0, 42955.7852210737, 43184.0, 44274.3358849624, 43604.78197609119, 44228.77885610551, 44651.0, 46409.76795116003, 47653.28520072132, 38073.23853522969, 38894.0, 39060.80469597634, 39935.60064399319, 39977.80011100042, 28990.61265802714, 29541.0, 29639.110826674372, 29747.55378669365, 30111.548326775555], "value": 20908.68563030884, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.55076140864355, 412.79979210083087, 414.4301585320183, 416.57717416821924, 418.4622759753717, 14099.859001409983, 17721.0, 19435.416937491864, 19664.705029424553, 19675.901620491895, 13898.305084745747, 16901.408450704228, 16924.49226523203, 18568.16443260053, 19275.325363612275, 5470.070088085035, 5653.293338332708, 5656.802011929583, 5686.800961966332, 5707.1153971134545, 5590.105583106703, 5610.607257491977, 5617.831465056034, 5629.831105066854, 5666.263385759866, 4022.235775202717, 5864.941350586503, 6160.84597885052, 6171.382861713838, 6279.623222606659, 6163.3836616338, 6194.070889366609, 6204.968975155118, 6263.498920086405, 6286.277078136027, 5404.32445944257, 5503.9174412383845, 5559.777608895639, 5582.134769110794, 5585.888282234332, 23000.53998920029, 23184.304470865733, 23211.3036608902, 23215.93721098801, 23649.645255321186, 2420.697412823397, 2443.3280847766828, 2508.2099138771227, 2649.9274944875956, 2933.9706602934125, 9992.500374981222, 10549.789004219872, 14073.36669849849, 14434.35045422948, 15039.924800376037, 1965.5184479802397, 1978.3768113044346, 1982.8810271383618, 2013.32553594546, 2045.6624656931738, 80630.0, 80869.0, 81612.18387816106, 82759.0, 84021.57989210016, 11107.83338249927, 11267.267627604344, 11299.322040677585, 11302.321860688386, 11438.08328876148, 15699.764503532457, 15791.210439478209, 15816.446424375092, 15836.129012904401, 15837.445689400816, 7918.881216781753, 8068.3948703847445, 8151.918480815235, 8231.670733170726, 8237.958810205912, 7636.0, 7652.579108148994, 7699.730509432143, 7793.220677932275, 7821.5698136601895, 4499.190145773727, 4595.333676616872, 4886.804527818847, 4890.413150421901, 5042.243663450512, 1971.9211231550578, 1985.5631761012555, 2049.9590008199752, 2062.6905964105504, 2064.4838790302424, 934.3506263147184, 1029.5933106423072, 1056.6196169379018, 1117.6982214801958, 1123.3035517978906, 18651.253949842125, 19339.0, 19455.12451939666, 19499.122539485757, 19527.414177574694, 1022.621629996891, 1093.6445655162104, 1093.6773651772746, 1110.161827820007, 1116.6873275482878, 10338.638147664795, 10965.177611679155, 11031.117510599133, 11063.336199828036, 11069.72325691848, 469822.0, 473068.0, 474084.0, 485269.0, 485864.0, 91841.54079229561, 96585.51707241559, 97877.51061244648, 98389.50805245928, 98832.50583747035, 1030.9639162629421, 1046.1735229168871, 1048.8164571200161, 1049.1134990932649, 1061.5010944855906, 17152.828471715122, 17155.742663860303, 17310.913445432692, 17348.132593370534, 17472.30110795554, 38548.229035418575, 39269.0, 40149.59850401458, 41847.58152418437, 42043.0, 57807.421925781884, 57813.4218657808, 59504.0, 59651.701741492754, 59766.0, 7723.266289702469, 8024.2376974187355, 8030.959845200737, 8098.959505202436, 8606.139386061344, 9006.369554131148, 9124.871625677988, 9167.587458564247, 12188.573399931314, 13202.669933251746, 11863.881361186277, 12095.274283543016, 12222.572209972608, 12333.075019373762, 12338.876611234133, 5817.563682723728, 8337.916620833714, 9353.643721660457, 11775.587854425054, 12293.770622937634, 6077.908831367621, 9991.900080999098, 10861.42509336126, 12188.75622487563, 12262.816057758962, 8793.560321984003, 9026.864597030919, 9053.094690531158, 9115.908840911772, 9132.77168070777, 9097.363184577016, 11508.13688973347, 12006.279623222284, 12251.08116891219, 12261.816072758736], "value": 9225.789204623266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1197.4072833947198, 1492.5373134328356, 1516.636007358234, 1684.5199118251294, 1826.5707558723698, 8592.785180370485, 8982.281417486602, 9019.864702029476, 9249.815003699921, 9267.490288034156, 9042.547872606376, 9243.306751993594, 9253.398529095604, 9255.629774809016, 9256.768580785467, 2075.3255192062566, 2333.731620863597, 2371.8576885386847, 2403.819713521484, 2410.433548116191, 2395.784379405853, 2402.615581506959, 2403.3030421177837, 2405.458771776345, 2407.109369533275, 2396.772306630876, 2453.3498622864954, 2453.5338285725657, 2453.5338285725657, 2457.0908763757348, 2060.4024832798546, 2353.3410645019335, 2424.187897054489, 2425.8180636452335, 2431.720352159489, 2402.0752010475917, 2420.9757902420924, 2432.1122790181635, 2437.500312435964, 2472.320111969205, 8941.374103812735, 8952.462852228888, 9038.276937844958, 9040.819183616291, 9042.23141033013, 5336.30628018359, 5449.073657478239, 5462.262594549725, 5474.315710536183, 5475.06923822951, 3691.280200360931, 3715.702743780491, 3771.302309072803, 3801.6958643308467, 3811.1424929390664, 1143.5654451308533, 1257.2079589858422, 2170.0378982688676, 2175.4126385875884, 2179.4115588791096, 12134.514619415213, 12520.499180032879, 12541.811872821823, 12545.811812822814, 12559.372031398394, 4740.336352910595, 4744.217204161275, 4814.638902082358, 4856.150173719585, 4861.489543597942, 11014.73330566979, 11018.063464605439, 11019.779604407868, 11020.834687479693, 11020.834687479693, 4677.579017888406, 5372.247885296062, 5425.538829199562, 5429.864253393618, 5430.294061771944, 4916.115099282163, 5006.1739812930455, 5017.84946451607, 5041.924371134436, 5052.520010598987, 4076.7158345121193, 4098.672106231495, 4127.4522054229665, 4193.874183774492, 4201.894952626209, 1259.1249081888, 1282.39086433945, 1285.2673975833873, 1289.767841788487, 1300.4928078049568, 964.5707660091332, 990.8563258327503, 1065.7708592652616, 1071.1430855315716, 1076.6231818863334, 6320.968395158086, 6357.904631430532, 6370.872582548323, 6489.805305840832, 6592.945128779373, 465.24165610055394, 468.9788959496831, 471.40838247998545, 472.0464661384054, 480.64912613792126, 4330.848420305274, 4423.270160423558, 4432.468103827561, 4456.242438785414, 4465.6427485801705, 11287.774244515063, 11523.53905843757, 11559.248648837967, 11606.651800446, 11687.590934317259, 10531.210159237935, 10933.125349971982, 11366.09071274296, 11492.195546311845, 11531.13516486267, 8348.248657620843, 8405.747827565061, 8511.574421279036, 8526.062133165347, 8550.57247137653, 6388.744450221939, 6635.8009259722285, 6660.933390666225, 6707.0, 6754.088198093391, 10088.546015429176, 10541.631042913461, 10555.577776889146, 10575.735606609896, 10693.518791654238, 10478.738031549272, 10789.62236321725, 10809.783804324026, 10890.61882834097, 10909.509072091616, 7010.859782804418, 7866.174051724715, 7974.16271291506, 8066.798330041561, 8132.796680083045, 8974.41666291689, 8997.415168014059, 9004.864927025968, 9050.864237036581, 9083.500407477652, 7570.318671319607, 7689.3848492121615, 7829.45193836426, 7860.960695196487, 7863.685452582062, 7782.143964163824, 7998.160193179865, 8029.638666259915, 8116.75649730509, 8124.22819832115, 7508.361789247757, 7526.0, 7539.283768042027, 7593.316601505891, 7632.274933881273, 5620.353659329229, 5624.072028115315, 5629.296337957755, 5725.312962444366, 5764.625299355528, 5704.80033198836, 5722.198892155185, 5740.167675687086, 5803.071508558611, 5950.821475355746], "value": 4814.362310834532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.03034397483134, 343.4127641732637, 350.59156083163106, 352.75659794741625, 354.04584644383397, 5285.471452854717, 5318.1490961446125, 5327.547158491523, 5343.251944727736, 5367.369988705535, 5249.401546234619, 5296.390915044771, 5296.973515132419, 5299.8410047698535, 5308.893822123555, 3973.5231772187376, 4057.57395473474, 4169.791510424482, 4181.393697913801, 4210.326347784355, 4152.626263636272, 4186.58134186582, 4187.225363307783, 4188.769617671027, 4202.852900148496, 3969.8412063517426, 4148.917021659565, 4151.214977559648, 4159.688023398242, 4161.791910404483, 4013.8595149169787, 4149.481314835645, 4160.8363650901665, 4166.729162604421, 4167.541570427251, 3696.149885526317, 3700.389435743099, 3700.833462494194, 3702.6855466309344, 3703.870274592093, 5065.822696205652, 5083.8983220335385, 5099.0, 5101.7194054327, 5107.846764597068, 4442.222611043055, 4687.507811679793, 4700.1539722849875, 4702.130105930414, 4703.824043989002, 5474.37044739856, 5479.506844383985, 5500.4499550045075, 5519.448055194489, 5561.0, 3886.145048089416, 3890.3580909149955, 3911.765294082364, 3917.4907262056036, 3927.5483319418345, 5530.972345138289, 5687.601867869251, 5697.6866272355, 5712.942870571283, 5737.913931291076, 4012.27778999777, 4012.337964235868, 4021.617946295097, 4039.777812220297, 4049.8582549610624, 4090.2433049885867, 4090.3250963590676, 4091.118085680561, 4092.0, 4110.917781644351, 4512.52618475062, 4687.695299805502, 4697.201475749131, 4704.647151463654, 4707.81168753246, 5500.972495137498, 5502.724863756796, 5519.613627046068, 5520.558355331564, 5536.723163841792, 1397.4480080368198, 1399.6011136825955, 1403.5929580421564, 1412.2938530734632, 1416.2776983738368, 896.914793094655, 900.8873890763655, 919.7240827751648, 922.294444749767, 942.8632848237104, 5234.581233501387, 5262.368515778055, 5262.868428289246, 5323.22813192085, 5350.625456218105, 5685.374608793029, 5690.800821971211, 5695.345035320991, 5697.401772813795, 5704.629199102128, 5128.948710512922, 5330.360356757214, 5331.1470164773455, 5345.22494238341, 5351.411344752074, 3028.545718142252, 3034.5903303054247, 3034.620672415948, 3037.635483741921, 3047.9542806857917, 5637.7181140942785, 5734.655920644691, 5801.361850196475, 5878.970605147032, 5883.0, 5628.324601047819, 5673.489385955284, 5687.43125687428, 5693.117566777156, 5726.341470730836, 5733.283339582552, 5744.425557444222, 5747.339056008529, 5770.336411312753, 5792.768289268466, 5445.727713614224, 5585.413531579125, 5590.944090559043, 5658.575606829587, 5664.5751568631695, 5593.748281327432, 5595.5523558116065, 5609.27079479665, 5627.606067575231, 5637.46444087811, 5486.670799752028, 5801.912971305518, 5835.44563266489, 5835.94164058371, 5847.0, 3997.2405243003736, 4013.41805438204, 4020.8592699255387, 4025.0138716013594, 4037.454943582696, 5584.636998595079, 5610.242617246619, 5635.633683810539, 5645.520130788929, 5658.264425624807, 5317.8404647860625, 5464.80873169453, 5499.4225606310765, 5499.50504454601, 5502.284702988746, 5362.839114826562, 5486.506214440719, 5487.890242195213, 5507.917381239365, 5511.834644960658, 5226.700251889157, 5403.540699040625, 5415.106507426231, 5433.402325744086, 5439.156930675752, 5136.717480538607, 5544.279243698294, 5550.222968784293, 5552.33371995347, 5609.411011843861, 5451.100568406168, 5471.37079235893, 5480.383286930445, 5492.945070549244, 5508.614396992172], "value": 4212.798912526623, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.793977686248, 780.2743448592809, 845.3533047218875, 846.7459762071377, 874.5933141089388, 13921.721565568683, 14658.63353416165, 14773.852261477383, 15015.399384024626, 15026.173560454185, 14273.857261427383, 14804.703905921875, 14816.703665926674, 14829.110253384777, 14835.109893406403, 712.1881055596624, 835.5362773660615, 854.9871751923727, 855.1918437076964, 861.1431625532604, 862.2670729879615, 863.1411745313422, 865.074370423647, 865.4547634989948, 865.5974971638187, 738.9852202955938, 885.1812073831712, 886.6276164011131, 887.6937456577502, 888.0276097673037, 874.7375787263795, 882.100257737111, 883.3639779358851, 891.0243283604443, 893.6380765789866, 647.3526473526474, 648.6205569741707, 648.870225954811, 649.4025496543192, 650.1645385679426, 14102.224377659328, 14124.717505649933, 14127.78808317876, 14129.293535323295, 14149.221792801392, 13626.863731362659, 13842.723145537133, 13887.58337249884, 14043.859561404357, 14324.85675143256, 12453.688657783538, 12664.0, 12711.872881271162, 12733.299668518228, 12760.170588911691, 4512.075024619978, 4519.525449827786, 4553.749543775091, 4554.863354099382, 4560.247559152735, 57842.710786446216, 58081.70959145219, 60030.39969600292, 60030.399696003355, 60259.69870150621, 10800.92173730483, 10829.783404331869, 10886.455677216107, 11351.148663850245, 11380.487878045507, 19754.90122549378, 19949.900250498944, 19953.501162471053, 20007.59984800325, 20020.69968950467, 8026.839463210703, 8089.0, 8114.918850811417, 8115.269625733712, 8235.95882020586, 4534.7052441591195, 4627.3521706961055, 4732.1009008289, 4739.099571081483, 4771.71369717818, 3373.39278929795, 3403.1832360233366, 3440.8107554084263, 3447.1554469155217, 3449.586049674055, 658.8122385120314, 661.7121552124842, 661.7797290091769, 662.460095022555, 675.7161991963387, 649.4966401039233, 651.0104640945736, 714.6462501062008, 746.8431629357787, 761.4859969520552, 10173.54219060144, 10471.0, 10535.104516116216, 10622.94688526568, 10624.681259562225, 673.4948788408693, 680.4760334542474, 720.0027961273569, 766.9769906902802, 795.9056297590813, 4984.850454486371, 5063.8987220255385, 5105.566026887682, 5112.795488180505, 5123.699606637197, 75701.62149189219, 77075.0, 78266.6086669563, 78852.0, 79204.6039769809, 67686.0, 68689.0, 68792.0, 69751.3024869755, 71022.64488677627, 61602.0, 61870.0719489208, 62234.0, 62262.0, 62689.37310626927, 9563.426194428079, 9771.462569558742, 9843.753906152117, 9886.85169722469, 9929.0, 41183.382249265684, 41400.0, 41529.58470415378, 41784.0, 41985.95035124147, 53404.46595533995, 55535.72232138814, 55968.72015639896, 55990.440095598526, 56016.71991640016, 11589.188756786943, 12179.208351457128, 12495.0, 12637.115401921777, 12866.742665146832, 10799.514021868878, 13578.0, 14098.50655227062, 14328.426862925366, 14528.854711453174, 13679.24764137982, 14137.081089729134, 14165.008449408442, 14259.500917467838, 14269.072510286798, 12923.483060677467, 13409.994250431062, 13619.25094119833, 13892.93053534726, 14084.0, 13503.189808611514, 13695.452181912611, 13760.0, 13871.167729936236, 14228.35972381266, 10968.396738179477, 11000.944995274973, 11200.887991120311, 11266.66200014001, 11795.941020294844, 11496.252743571642, 11654.825177622512, 11821.881781182077, 11984.28094314344, 11997.400129993292], "value": 7254.241601610659, "unit": "score"}], "name": "dromaeo_css", "value": 7334.449119930021, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 110.0, 109.0, 109.0, 110.0, 111.0, 110.0, 111.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 130.0, 149.0, 222.0, 125.0, 151.0, 150.0, 129.0, 183.0, 130.0], "value": 152.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 156.0, 75.0, 73.0, 75.0, 73.0, 129.0, 74.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 141.0, 92.0, 92.0, 92.0, 124.0, 92.0, 91.0, 124.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 145.0, 145.0], "value": 144.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 98.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 54.0, 53.0, 55.0, 113.0, 51.0, 51.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 78.0, 77.0, 83.0, 78.0, 80.0, 84.0, 78.0, 84.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 118.0, 119.0, 119.0, 116.0, 116.0, 119.0, 203.0, 119.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 159.0, 165.0, 165.0, 162.0, 160.0, 159.0, 159.0, 160.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 69.0, 63.0, 61.0, 63.0, 62.0, 62.0, 61.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1521.7, "alertThreshold": 2.0}]} 11:12:12 INFO - Return code: 0 11:12:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:12:12 ERROR - # TBPL SUCCESS # 11:12:12 INFO - Running post-action listener: _resource_record_post_action 11:12:12 INFO - ##### 11:12:12 INFO - ##### Finished run-tests step (success) 11:12:12 INFO - ##### 11:12:12 INFO - Running post-run listener: _resource_record_post_run 11:12:12 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 536354816; Read time: 0; Write time: 434616 11:12:12 INFO - TinderboxPrint: CPU usage
13.1% 11:12:12 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:12:12 INFO - TinderboxPrint: I/O write bytes / time
536,354,816 / 434,616 11:12:12 INFO - TinderboxPrint: CPU idle
8,273.8 (86.9%) 11:12:12 INFO - TinderboxPrint: CPU user
1,201.5 (12.6%) 11:12:12 INFO - TinderboxPrint: Swap in / out
0 / 0 11:12:12 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 237326336; Read time: 0; Write time: 234204 11:12:12 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 289849344; Read time: 0; Write time: 193236 11:12:13 INFO - Running post-run listener: _upload_blobber_files 11:12:13 INFO - Blob upload gear active. 11:12:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:12:13 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 11:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:12:13 INFO - (blobuploader) - INFO - Open directory for files ... 11:12:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:12:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:12:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:17 INFO - (blobuploader) - INFO - Done attempting. 11:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:18 INFO - (blobuploader) - INFO - Done attempting. 11:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:18 INFO - (blobuploader) - INFO - Done attempting. 11:12:18 INFO - (blobuploader) - INFO - Iteration through files over. 11:12:18 INFO - Return code: 0 11:12:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:12:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:12:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a6bb377252c7cd300f55a542e9116f460c3177922127986cad8a7206da65d216b54adea045561768e76d9fcb8653a5fe23190b259d767c5be0e128b692265e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6700d6e5027194bcd879c5ee58d1fded2c9db4dc8f55e36e84b0bbb1ce1b62d2d76a1864f31f731b3dbe34168724bc77004a54c5e0184b561012253e938d08cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/970792fad6bfdc96018fcc27159ab4de1220ece9fd880111389999644f9a6c93d3434ca63f6727bebb9de044ed5c88bc4413d02da946845444c0b9ac27792377"} 11:12:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:12:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:12:18 INFO - Contents: 11:12:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a6bb377252c7cd300f55a542e9116f460c3177922127986cad8a7206da65d216b54adea045561768e76d9fcb8653a5fe23190b259d767c5be0e128b692265e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6700d6e5027194bcd879c5ee58d1fded2c9db4dc8f55e36e84b0bbb1ce1b62d2d76a1864f31f731b3dbe34168724bc77004a54c5e0184b561012253e938d08cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/970792fad6bfdc96018fcc27159ab4de1220ece9fd880111389999644f9a6c93d3434ca63f6727bebb9de044ed5c88bc4413d02da946845444c0b9ac27792377"} 11:12:18 INFO - Running post-run listener: copy_logs_to_upload_dir 11:12:18 INFO - Copying logs to upload dir... 11:12:18 INFO - mkdir: /builds/slave/test/build/upload/logs 11:12:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.365758 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-20 11:12:18.475497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 11:12:18.480106) ========= 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=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a6bb377252c7cd300f55a542e9116f460c3177922127986cad8a7206da65d216b54adea045561768e76d9fcb8653a5fe23190b259d767c5be0e128b692265e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6700d6e5027194bcd879c5ee58d1fded2c9db4dc8f55e36e84b0bbb1ce1b62d2d76a1864f31f731b3dbe34168724bc77004a54c5e0184b561012253e938d08cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/970792fad6bfdc96018fcc27159ab4de1220ece9fd880111389999644f9a6c93d3434ca63f6727bebb9de044ed5c88bc4413d02da946845444c0b9ac27792377"} build_url:https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/RUNZE3WHQOuP_nEzjDj3RA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a6bb377252c7cd300f55a542e9116f460c3177922127986cad8a7206da65d216b54adea045561768e76d9fcb8653a5fe23190b259d767c5be0e128b692265e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6700d6e5027194bcd879c5ee58d1fded2c9db4dc8f55e36e84b0bbb1ce1b62d2d76a1864f31f731b3dbe34168724bc77004a54c5e0184b561012253e938d08cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/970792fad6bfdc96018fcc27159ab4de1220ece9fd880111389999644f9a6c93d3434ca63f6727bebb9de044ed5c88bc4413d02da946845444c0b9ac27792377"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 11:12:18.507906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 11:12:18.508235) ========= 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=8887e36bb8e381bfd44124ac000001a8-1463766702.129405-1476108483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 11:12:18.591346) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 11:12:18.591652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 11:12:18.591938) ========= ========= Total master_lag: 0.18 =========