builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1478308558.36 results: success (0) buildid: 20161104153921 builduid: f7ab970018ed461db85f91177a2fa7af revision: 3888f1a5702b34824b618b2110e09be7965477c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.363871) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.364364) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.364670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.379318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.379702) ========= 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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-04 18:15:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.8M=0s 2016-11-04 18:15:58 (30.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101580 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.495037) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.495389) ========= 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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018958 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.546315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:58.546689) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3888f1a5702b34824b618b2110e09be7965477c5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3888f1a5702b34824b618b2110e09be7965477c5 --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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-04 18:15:59,092 truncating revision to first 12 chars 2016-11-04 18:15:59,092 Setting DEBUG logging. 2016-11-04 18:15:59,092 attempt 1/10 2016-11-04 18:15:59,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3888f1a5702b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-04 18:15:59,486 unpacking tar archive at: mozilla-aurora-3888f1a5702b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.769012 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:59.329698) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:59.330031) ========= script_repo_revision: 3888f1a5702b34824b618b2110e09be7965477c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:59.330480) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:59.330753) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 18:15:59.340694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-04 18:15:59.340978) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:59 INFO - MultiFileLogger online at 20161104 18:15:59 in /builds/slave/test 18:15:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:15:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:59 INFO - {'append_to_log': False, 18:15:59 INFO - 'base_work_dir': '/builds/slave/test', 18:15:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:15:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:59 INFO - 'branch': 'Mozilla-Aurora', 18:15:59 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:59 INFO - 'config_files': ('talos/linux_config.py',), 18:15:59 INFO - 'default_actions': ('clobber', 18:15:59 INFO - 'read-buildbot-config', 18:15:59 INFO - 'download-and-extract', 18:15:59 INFO - 'populate-webroot', 18:15:59 INFO - 'create-virtualenv', 18:15:59 INFO - 'install', 18:15:59 INFO - 'run-tests'), 18:15:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:59 INFO - 'download_minidump_stackwalk': True, 18:15:59 INFO - 'download_symbols': 'ondemand', 18:15:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:59 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:59 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:59 INFO - 'installer_path': 'installer.exe', 18:15:59 INFO - 'log_level': 'info', 18:15:59 INFO - 'log_name': 'talos', 18:15:59 INFO - 'log_to_console': True, 18:15:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:59 INFO - 'opt_config_files': (), 18:15:59 INFO - 'pip_index': False, 18:15:59 INFO - 'sps_profile': False, 18:15:59 INFO - 'sps_profile_interval': 0, 18:15:59 INFO - 'suite': 'dromaeojs', 18:15:59 INFO - 'system_bits': '32', 18:15:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:59 INFO - 'title': 'talos-linux64-ix-053', 18:15:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:59 INFO - 'use_talos_json': True, 18:15:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:59 INFO - 'work_dir': 'build'} 18:15:59 INFO - [mozharness: 2016-11-05 01:15:59.981836Z] Running clobber step. 18:15:59 INFO - Running pre-action listener: _resource_record_pre_action 18:15:59 INFO - Running main action method: clobber 18:15:59 INFO - rmtree: /builds/slave/test/build 18:15:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:16:00 INFO - Running post-action listener: _resource_record_post_action 18:16:00 INFO - [mozharness: 2016-11-05 01:16:00.440588Z] Finished clobber step (success) 18:16:00 INFO - [mozharness: 2016-11-05 01:16:00.440689Z] Running read-buildbot-config step. 18:16:00 INFO - Running pre-action listener: _resource_record_pre_action 18:16:00 INFO - Running main action method: read_buildbot_config 18:16:00 INFO - Using buildbot properties: 18:16:00 INFO - { 18:16:00 INFO - "project": "", 18:16:00 INFO - "product": "firefox", 18:16:00 INFO - "script_repo_revision": "production", 18:16:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:16:00 INFO - "repository": "", 18:16:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:16:00 INFO - "buildid": "20161104153921", 18:16:00 INFO - "pgo_build": "True", 18:16:00 INFO - "basedir": "/builds/slave/test", 18:16:00 INFO - "buildnumber": 572, 18:16:00 INFO - "slavename": "talos-linux64-ix-053", 18:16:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:16:00 INFO - "platform": "ubuntu64_hw", 18:16:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:16:00 INFO - "branch": "mozilla-aurora", 18:16:00 INFO - "repo_path": "releases/mozilla-aurora", 18:16:00 INFO - "revision": "3888f1a5702b34824b618b2110e09be7965477c5", 18:16:00 INFO - "stage_platform": "linux64-pgo", 18:16:00 INFO - "builduid": "f7ab970018ed461db85f91177a2fa7af", 18:16:00 INFO - "slavebuilddir": "test" 18:16:00 INFO - } 18:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 18:16:00 INFO - Running post-action listener: _resource_record_post_action 18:16:00 INFO - [mozharness: 2016-11-05 01:16:00.443134Z] Finished read-buildbot-config step (success) 18:16:00 INFO - [mozharness: 2016-11-05 01:16:00.443224Z] Running download-and-extract step. 18:16:00 INFO - Running pre-action listener: _resource_record_pre_action 18:16:00 INFO - Running main action method: download_and_extract 18:16:00 INFO - mkdir: /builds/slave/test/build/tests 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - trying https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:16:01 INFO - Downloaded 1361 bytes. 18:16:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:16:01 INFO - Using the following test package requirements: 18:16:01 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 18:16:01 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:16:01 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:16:01 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'jsshell-linux-x86_64.zip'], 18:16:01 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:16:01 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 18:16:01 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:16:01 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:16:01 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:16:01 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:16:01 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 18:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 18:16:01 INFO - Expected file size: 21895374 18:16:05 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:16:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 18:16:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:16:05 INFO - Fetch https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 18:16:06 INFO - Expected file size: 21895374 18:16:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 18:16:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:16:11 INFO - Fetch https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 18:16:11 INFO - Expected file size: 14414089 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:12 INFO - trying https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:16:12 INFO - Downloading https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:16:13 INFO - Downloaded 63983235 bytes. 18:16:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:16:13 INFO - mkdir: /builds/slave/test/properties 18:16:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:13 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:13 INFO - Contents: 18:16:13 INFO - build_url:https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:16:14 INFO - Running post-action listener: _resource_record_post_action 18:16:14 INFO - Running post-action listener: set_extra_try_arguments 18:16:14 INFO - [mozharness: 2016-11-05 01:16:14.059174Z] Finished download-and-extract step (success) 18:16:14 INFO - [mozharness: 2016-11-05 01:16:14.059403Z] Running populate-webroot step. 18:16:14 INFO - Running pre-action listener: _resource_record_pre_action 18:16:14 INFO - Running main action method: populate_webroot 18:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:16:14 INFO - '--tppagecycles', 18:16:14 INFO - '7'], 18:16:14 INFO - 'tests': ['tsvgm']}}, 18:16:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:16:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:16:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['damp', 'tps']}, 18:16:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'tests': ['damp', 'tps']}, 18:16:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['dromaeo_dom']}, 18:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['basic_compositor_video']}, 18:16:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:16:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['a11yr', 18:16:14 INFO - 'ts_paint', 18:16:14 INFO - 'tpaint', 18:16:14 INFO - 'sessionrestore', 18:16:14 INFO - 'sessionrestore_no_auto_restore', 18:16:14 INFO - 'tabpaint']}, 18:16:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['tsvgx', 18:16:14 INFO - 'tsvgr_opacity', 18:16:14 INFO - 'tart', 18:16:14 INFO - 'tscrollx', 18:16:14 INFO - 'cart']}, 18:16:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:14 INFO - 'tsvgr_opacity', 18:16:14 INFO - 'tart', 18:16:14 INFO - 'tscrollx', 18:16:14 INFO - 'cart']}, 18:16:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'talos_options': ['--disable-e10s'], 18:16:14 INFO - 'tests': ['tp5o']}, 18:16:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'tests': ['tp5o']}, 18:16:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'talos_options': ['--disable-e10s', 18:16:14 INFO - '--xperf_path', 18:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:14 INFO - 'tests': ['tp5n']}, 18:16:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:14 INFO - 'talos_options': ['--xperf_path', 18:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:14 INFO - 'tests': ['tp5n']}}, 18:16:14 INFO - 'talos.zip': {'path': '', 18:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:16:14 INFO - Running post-action listener: _resource_record_post_action 18:16:14 INFO - [mozharness: 2016-11-05 01:16:14.077979Z] Finished populate-webroot step (success) 18:16:14 INFO - [mozharness: 2016-11-05 01:16:14.078068Z] Running create-virtualenv step. 18:16:14 INFO - Running pre-action listener: _resource_record_pre_action 18:16:14 INFO - Running main action method: create_virtualenv 18:16:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:14 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 18:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:16:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:14 INFO - Using real prefix '/usr' 18:16:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:14 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:16 INFO - Installing pip.................done. 18:16:16 INFO - Return code: 0 18:16:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:16 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:16 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 18:16:16 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 18:16:16 INFO - Using env: {'DISPLAY': ':0', 18:16:16 INFO - 'HOME': '/home/cltbld', 18:16:16 INFO - 'LANG': 'en_US.UTF-8', 18:16:16 INFO - 'LANGUAGE': 'en_US:en', 18:16:16 INFO - 'LOGNAME': 'cltbld', 18:16:16 INFO - 'MAIL': '/var/mail/cltbld', 18:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:16 INFO - 'MOZ_NO_REMOTE': '1', 18:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:16 INFO - 'NO_EM_RESTART': '1', 18:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:16 INFO - 'PWD': '/builds/slave/test', 18:16:16 INFO - 'SHELL': '/bin/bash', 18:16:16 INFO - 'SHLVL': '1', 18:16:16 INFO - 'TERM': 'linux', 18:16:16 INFO - 'TMOUT': '86400', 18:16:16 INFO - 'USER': 'cltbld', 18:16:16 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:16 INFO - '_': '/tools/buildbot/bin/python'} 18:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:16 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 18:16:16 INFO - Cleaning up... 18:16:16 INFO - Return code: 0 18:16:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:16 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:16 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 18:16:16 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 18:16:16 INFO - Using env: {'DISPLAY': ':0', 18:16:16 INFO - 'HOME': '/home/cltbld', 18:16:16 INFO - 'LANG': 'en_US.UTF-8', 18:16:16 INFO - 'LANGUAGE': 'en_US:en', 18:16:16 INFO - 'LOGNAME': 'cltbld', 18:16:16 INFO - 'MAIL': '/var/mail/cltbld', 18:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:16 INFO - 'MOZ_NO_REMOTE': '1', 18:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:16 INFO - 'NO_EM_RESTART': '1', 18:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:16 INFO - 'PWD': '/builds/slave/test', 18:16:16 INFO - 'SHELL': '/bin/bash', 18:16:16 INFO - 'SHLVL': '1', 18:16:16 INFO - 'TERM': 'linux', 18:16:16 INFO - 'TMOUT': '86400', 18:16:16 INFO - 'USER': 'cltbld', 18:16:16 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:16 INFO - '_': '/tools/buildbot/bin/python'} 18:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:17 INFO - Downloading/unpacking psutil>=3.1.1 18:16: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 18:16: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 18:16: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 18:16: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 18:16: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 18:16: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 18:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:19 INFO - Installing collected packages: psutil 18:16:19 INFO - Running setup.py install for psutil 18:16:19 INFO - building 'psutil._psutil_linux' extension 18:16:19 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 18:16:19 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 18:16:19 INFO - building 'psutil._psutil_posix' extension 18:16:19 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 18:16:19 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 18:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:19 INFO - Successfully installed psutil 18:16:19 INFO - Cleaning up... 18:16:19 INFO - Return code: 0 18:16:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:19 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:19 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 18:16:19 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 18:16:19 INFO - Using env: {'DISPLAY': ':0', 18:16:19 INFO - 'HOME': '/home/cltbld', 18:16:19 INFO - 'LANG': 'en_US.UTF-8', 18:16:19 INFO - 'LANGUAGE': 'en_US:en', 18:16:19 INFO - 'LOGNAME': 'cltbld', 18:16:19 INFO - 'MAIL': '/var/mail/cltbld', 18:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:19 INFO - 'MOZ_NO_REMOTE': '1', 18:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:19 INFO - 'NO_EM_RESTART': '1', 18:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:19 INFO - 'PWD': '/builds/slave/test', 18:16:19 INFO - 'SHELL': '/bin/bash', 18:16:19 INFO - 'SHLVL': '1', 18:16:19 INFO - 'TERM': 'linux', 18:16:19 INFO - 'TMOUT': '86400', 18:16:19 INFO - 'USER': 'cltbld', 18:16:19 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:19 INFO - '_': '/tools/buildbot/bin/python'} 18:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16:19 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 18:16:19 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 18:16:19 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 18:16:19 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 18:16:19 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 18:16:19 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 18:16:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:16:21 INFO - Installing collected packages: mozsystemmonitor 18:16:21 INFO - Running setup.py install for mozsystemmonitor 18:16:21 INFO - Successfully installed mozsystemmonitor 18:16:21 INFO - Cleaning up... 18:16:22 INFO - Return code: 0 18:16:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:22 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:22 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==2.5.1'] in /builds/slave/test/build 18:16:22 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==2.5.1 18:16:22 INFO - Using env: {'DISPLAY': ':0', 18:16:22 INFO - 'HOME': '/home/cltbld', 18:16:22 INFO - 'LANG': 'en_US.UTF-8', 18:16:22 INFO - 'LANGUAGE': 'en_US:en', 18:16:22 INFO - 'LOGNAME': 'cltbld', 18:16:22 INFO - 'MAIL': '/var/mail/cltbld', 18:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:22 INFO - 'MOZ_NO_REMOTE': '1', 18:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:22 INFO - 'NO_EM_RESTART': '1', 18:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:22 INFO - 'PWD': '/builds/slave/test', 18:16:22 INFO - 'SHELL': '/bin/bash', 18:16:22 INFO - 'SHLVL': '1', 18:16:22 INFO - 'TERM': 'linux', 18:16:22 INFO - 'TMOUT': '86400', 18:16:22 INFO - 'USER': 'cltbld', 18:16:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:22 INFO - '_': '/tools/buildbot/bin/python'} 18:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:16:22 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 18:16:22 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 18:16:22 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 18:16:22 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 18:16:22 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 18:16:22 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 18:16:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:16:24 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 18:16:24 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 18:16:24 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 18:16:24 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 18:16:24 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 18:16:24 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 18:16:24 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:24 INFO - warning: no files found matching '*.txt' 18:16:24 INFO - no previously-included directories found matching 'build' 18:16:24 INFO - no previously-included directories found matching 'dist' 18:16:24 INFO - no previously-included directories found matching '.git*' 18:16:24 INFO - Installing collected packages: jsonschema, functools32 18:16:24 INFO - Running setup.py install for functools32 18:16:24 INFO - warning: no files found matching '*.txt' 18:16:24 INFO - no previously-included directories found matching 'build' 18:16:24 INFO - no previously-included directories found matching 'dist' 18:16:24 INFO - no previously-included directories found matching '.git*' 18:16:24 INFO - Successfully installed jsonschema functools32 18:16:24 INFO - Cleaning up... 18:16:24 INFO - Return code: 0 18:16:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:16:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:24 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:16:24 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 functools32==3.2.3-2 18:16:24 INFO - Using env: {'DISPLAY': ':0', 18:16:24 INFO - 'HOME': '/home/cltbld', 18:16:24 INFO - 'LANG': 'en_US.UTF-8', 18:16:24 INFO - 'LANGUAGE': 'en_US:en', 18:16:24 INFO - 'LOGNAME': 'cltbld', 18:16:24 INFO - 'MAIL': '/var/mail/cltbld', 18:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:24 INFO - 'MOZ_NO_REMOTE': '1', 18:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:24 INFO - 'NO_EM_RESTART': '1', 18:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:24 INFO - 'PWD': '/builds/slave/test', 18:16:24 INFO - 'SHELL': '/bin/bash', 18:16:24 INFO - 'SHLVL': '1', 18:16:24 INFO - 'TERM': 'linux', 18:16:24 INFO - 'TMOUT': '86400', 18:16:24 INFO - 'USER': 'cltbld', 18:16:24 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:24 INFO - '_': '/tools/buildbot/bin/python'} 18:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:16:24 INFO - Cleaning up... 18:16:24 INFO - Return code: 0 18:16:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:24 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 18:16:24 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 18:16:24 INFO - Using env: {'DISPLAY': ':0', 18:16:24 INFO - 'HOME': '/home/cltbld', 18:16:24 INFO - 'LANG': 'en_US.UTF-8', 18:16:24 INFO - 'LANGUAGE': 'en_US:en', 18:16:24 INFO - 'LOGNAME': 'cltbld', 18:16:24 INFO - 'MAIL': '/var/mail/cltbld', 18:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:24 INFO - 'MOZ_NO_REMOTE': '1', 18:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:24 INFO - 'NO_EM_RESTART': '1', 18:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:24 INFO - 'PWD': '/builds/slave/test', 18:16:24 INFO - 'SHELL': '/bin/bash', 18:16:24 INFO - 'SHLVL': '1', 18:16:24 INFO - 'TERM': 'linux', 18:16:24 INFO - 'TMOUT': '86400', 18:16:24 INFO - 'USER': 'cltbld', 18:16:24 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:24 INFO - '_': '/tools/buildbot/bin/python'} 18:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:25 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 18:16:25 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 18:16:25 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 18:16:25 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 18:16:25 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 18:16:25 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 18:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 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 18:16:27 INFO - Downloading docopt-0.6.1.tar.gz 18:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:27 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:27 INFO - Running setup.py install for blobuploader 18:16:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:27 INFO - Running setup.py install for requests 18:16:28 INFO - Running setup.py install for docopt 18:16:28 INFO - Successfully installed blobuploader requests docopt 18:16:28 INFO - Cleaning up... 18:16:28 INFO - Return code: 0 18:16:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:28 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:28 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 18:16:28 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 18:16:28 INFO - Using env: {'DISPLAY': ':0', 18:16:28 INFO - 'HOME': '/home/cltbld', 18:16:28 INFO - 'LANG': 'en_US.UTF-8', 18:16:28 INFO - 'LANGUAGE': 'en_US:en', 18:16:28 INFO - 'LOGNAME': 'cltbld', 18:16:28 INFO - 'MAIL': '/var/mail/cltbld', 18:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:28 INFO - 'MOZ_NO_REMOTE': '1', 18:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:28 INFO - 'NO_EM_RESTART': '1', 18:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:28 INFO - 'PWD': '/builds/slave/test', 18:16:28 INFO - 'SHELL': '/bin/bash', 18:16:28 INFO - 'SHLVL': '1', 18:16:28 INFO - 'TERM': 'linux', 18:16:28 INFO - 'TMOUT': '86400', 18:16:28 INFO - 'USER': 'cltbld', 18:16:28 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:28 INFO - '_': '/tools/buildbot/bin/python'} 18:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:28 INFO - Running setup.py (path:/tmp/pip-FIOAnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:28 INFO - Running setup.py (path:/tmp/pip-cAyKmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:28 INFO - Running setup.py (path:/tmp/pip-v9XmHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:28 INFO - Running setup.py (path:/tmp/pip-SQqPRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:28 INFO - Running setup.py (path:/tmp/pip-0fQWJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:29 INFO - Running setup.py (path:/tmp/pip-E_v5Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:29 INFO - Running setup.py (path:/tmp/pip-0jygtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:29 INFO - Running setup.py (path:/tmp/pip-JRdTVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:29 INFO - Running setup.py (path:/tmp/pip-gRw6F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:29 INFO - Running setup.py (path:/tmp/pip-VJ5wFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:29 INFO - Running setup.py (path:/tmp/pip-1n4ILd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:29 INFO - Running setup.py (path:/tmp/pip-8T80VT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:29 INFO - Running setup.py (path:/tmp/pip-DO9dqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:29 INFO - Running setup.py (path:/tmp/pip-nfIvfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:29 INFO - Running setup.py (path:/tmp/pip-fP15D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:29 INFO - Running setup.py (path:/tmp/pip-RM9Sw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:29 INFO - Running setup.py (path:/tmp/pip-JkznSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:29 INFO - Running setup.py install for manifestparser 18:16:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for mozcrash 18:16:30 INFO - Running setup.py install for mozdebug 18:16:30 INFO - Running setup.py install for mozdevice 18:16:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for mozfile 18:16:30 INFO - Running setup.py install for mozhttpd 18:16:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for mozinfo 18:16:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for mozInstall 18:16:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for mozleak 18:16:30 INFO - Running setup.py install for mozlog 18:16:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:30 INFO - Running setup.py install for moznetwork 18:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Running setup.py install for mozprocess 18:16:31 INFO - Running setup.py install for mozprofile 18:16:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Running setup.py install for mozrunner 18:16:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Running setup.py install for mozscreenshot 18:16:31 INFO - Running setup.py install for moztest 18:16:31 INFO - Running setup.py install for mozversion 18:16:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:31 INFO - Cleaning up... 18:16:31 INFO - Return code: 0 18:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:31 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:31 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 18:16:31 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 18:16:31 INFO - Using env: {'DISPLAY': ':0', 18:16:31 INFO - 'HOME': '/home/cltbld', 18:16:31 INFO - 'LANG': 'en_US.UTF-8', 18:16:31 INFO - 'LANGUAGE': 'en_US:en', 18:16:31 INFO - 'LOGNAME': 'cltbld', 18:16:31 INFO - 'MAIL': '/var/mail/cltbld', 18:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:31 INFO - 'MOZ_NO_REMOTE': '1', 18:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:31 INFO - 'NO_EM_RESTART': '1', 18:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:31 INFO - 'PWD': '/builds/slave/test', 18:16:31 INFO - 'SHELL': '/bin/bash', 18:16:31 INFO - 'SHLVL': '1', 18:16:31 INFO - 'TERM': 'linux', 18:16:31 INFO - 'TMOUT': '86400', 18:16:31 INFO - 'USER': 'cltbld', 18:16:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:31 INFO - '_': '/tools/buildbot/bin/python'} 18:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:31 INFO - Running setup.py (path:/tmp/pip-vEgMvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:32 INFO - Running setup.py (path:/tmp/pip-PSTO1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:32 INFO - Running setup.py (path:/tmp/pip-3LzlJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:32 INFO - Running setup.py (path:/tmp/pip-Xykq2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:32 INFO - Running setup.py (path:/tmp/pip-12OHVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:32 INFO - Running setup.py (path:/tmp/pip-FoAaMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:32 INFO - Running setup.py (path:/tmp/pip-x5xsNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:32 INFO - Running setup.py (path:/tmp/pip-Nx2U2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:32 INFO - Running setup.py (path:/tmp/pip-i3cQL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:32 INFO - Running setup.py (path:/tmp/pip-Y2nK_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:32 INFO - Running setup.py (path:/tmp/pip-FIbi68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:32 INFO - Running setup.py (path:/tmp/pip-QJ_EKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:32 INFO - Running setup.py (path:/tmp/pip-6mboVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:32 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:32 INFO - Running setup.py (path:/tmp/pip-WnoN9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:32 INFO - Running setup.py (path:/tmp/pip-Vm47sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:33 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)) 18:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:33 INFO - Running setup.py (path:/tmp/pip-DV8Zr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:33 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)) 18:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:33 INFO - Running setup.py (path:/tmp/pip-agYmNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:33 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)) 18:16:33 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)) 18:16:33 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)) 18:16:33 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)) 18:16:33 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)) 18:16:33 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)) 18:16:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:33 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 18:16:33 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 18:16:33 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 18:16:33 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 18:16:33 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 18:16:33 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 18:16:35 INFO - Downloading blessings-1.6.tar.gz 18:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:35 INFO - Installing collected packages: blessings 18:16:35 INFO - Running setup.py install for blessings 18:16:35 INFO - Successfully installed blessings 18:16:35 INFO - Cleaning up... 18:16:35 INFO - Return code: 0 18:16:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:35 INFO - Reading from file tmpfile_stdout 18:16:35 INFO - Current package versions: 18:16:35 INFO - argparse == 1.2.1 18:16:35 INFO - blessings == 1.6 18:16:35 INFO - blobuploader == 1.2.4 18:16:35 INFO - docopt == 0.6.1 18:16:35 INFO - functools32 == 3.2.3-2 18:16:35 INFO - jsonschema == 2.5.1 18:16:35 INFO - manifestparser == 1.1 18:16:35 INFO - mozInstall == 1.12 18:16:35 INFO - mozcrash == 0.17 18:16:35 INFO - mozdebug == 0.1 18:16:35 INFO - mozdevice == 0.48 18:16:35 INFO - mozfile == 1.2 18:16:35 INFO - mozhttpd == 0.7 18:16:35 INFO - mozinfo == 0.9 18:16:35 INFO - mozleak == 0.1 18:16:35 INFO - mozlog == 3.4 18:16:35 INFO - moznetwork == 0.27 18:16:35 INFO - mozprocess == 0.23 18:16:35 INFO - mozprofile == 0.28 18:16:35 INFO - mozrunner == 6.12 18:16:35 INFO - mozscreenshot == 0.1 18:16:35 INFO - mozsystemmonitor == 0.3 18:16:35 INFO - moztest == 0.7 18:16:35 INFO - mozversion == 1.4 18:16:35 INFO - psutil == 3.1.1 18:16:35 INFO - requests == 1.2.3 18:16:35 INFO - wsgiref == 0.1.2 18:16:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:35 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:35 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 18:16:35 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 18:16:35 INFO - Using env: {'DISPLAY': ':0', 18:16:35 INFO - 'HOME': '/home/cltbld', 18:16:35 INFO - 'LANG': 'en_US.UTF-8', 18:16:35 INFO - 'LANGUAGE': 'en_US:en', 18:16:35 INFO - 'LOGNAME': 'cltbld', 18:16:35 INFO - 'MAIL': '/var/mail/cltbld', 18:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:35 INFO - 'MOZ_NO_REMOTE': '1', 18:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:35 INFO - 'NO_EM_RESTART': '1', 18:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:35 INFO - 'PWD': '/builds/slave/test', 18:16:35 INFO - 'SHELL': '/bin/bash', 18:16:35 INFO - 'SHLVL': '1', 18:16:35 INFO - 'TERM': 'linux', 18:16:35 INFO - 'TMOUT': '86400', 18:16:35 INFO - 'USER': 'cltbld', 18:16:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:35 INFO - '_': '/tools/buildbot/bin/python'} 18:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 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)) 18:16:35 INFO - Cleaning up... 18:16:35 INFO - Return code: 0 18:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:35 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 0x7feb16a29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', '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 18:16:35 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 18:16:35 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 18:16:35 INFO - Using env: {'DISPLAY': ':0', 18:16:35 INFO - 'HOME': '/home/cltbld', 18:16:35 INFO - 'LANG': 'en_US.UTF-8', 18:16:35 INFO - 'LANGUAGE': 'en_US:en', 18:16:35 INFO - 'LOGNAME': 'cltbld', 18:16:35 INFO - 'MAIL': '/var/mail/cltbld', 18:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:35 INFO - 'MOZ_NO_REMOTE': '1', 18:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:35 INFO - 'NO_EM_RESTART': '1', 18:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:35 INFO - 'PWD': '/builds/slave/test', 18:16:35 INFO - 'SHELL': '/bin/bash', 18:16:35 INFO - 'SHLVL': '1', 18:16:35 INFO - 'TERM': 'linux', 18:16:35 INFO - 'TMOUT': '86400', 18:16:35 INFO - 'USER': 'cltbld', 18:16:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:35 INFO - '_': '/tools/buildbot/bin/python'} 18:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:35 INFO - Cleaning up... 18:16:36 INFO - Return code: 0 18:16:36 INFO - Running post-action listener: _resource_record_post_action 18:16:36 INFO - Running post-action listener: _start_resource_monitoring 18:16:36 INFO - Starting resource monitoring. 18:16:36 INFO - [mozharness: 2016-11-05 01:16:36.044919Z] Finished create-virtualenv step (success) 18:16:36 INFO - [mozharness: 2016-11-05 01:16:36.045374Z] Running install step. 18:16:36 INFO - Running pre-action listener: _resource_record_pre_action 18:16:36 INFO - Running main action method: install 18:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:36 INFO - Reading from file tmpfile_stdout 18:16:36 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:36 INFO - Reading from file tmpfile_stdout 18:16:36 INFO - Output received: 18:16:36 INFO - Usage: mozinstall [options] installer 18:16:36 INFO - Options: 18:16:36 INFO - -h, --help show this help message and exit 18:16:36 INFO - -d DEST, --destination=DEST 18:16:36 INFO - Directory to install application into. [default: 18:16:36 INFO - "/builds/slave/test"] 18:16:36 INFO - --app=APP Application being installed. [default: firefox] 18:16:36 INFO - mkdir: /builds/slave/test/build/application 18:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:16:52 INFO - Reading from file tmpfile_stdout 18:16:52 INFO - Output received: 18:16:52 INFO - /builds/slave/test/build/application/firefox/firefox 18:16:52 INFO - Running post-action listener: _resource_record_post_action 18:16:52 INFO - [mozharness: 2016-11-05 01:16:52.320955Z] Finished install step (success) 18:16:52 INFO - [mozharness: 2016-11-05 01:16:52.321287Z] Running run-tests step. 18:16:52 INFO - Running pre-action listener: _resource_record_pre_action 18:16:52 INFO - Running main action method: run_tests 18:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:52 INFO - Python 2.7.3 18:16:52 INFO - Return code: 0 18:16:52 INFO - grabbing minidump binary from tooltool 18:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:52 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 0x1b1bf60>, '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 0x1b1cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:52 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 18:16:52 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 18:16:52 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 18:16:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:52 INFO - Return code: 0 18:16:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:16:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:16:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 18:16:52 INFO - Using env: {'DISPLAY': ':0', 18:16:52 INFO - 'HOME': '/home/cltbld', 18:16:52 INFO - 'LANG': 'en_US.UTF-8', 18:16:52 INFO - 'LANGUAGE': 'en_US:en', 18:16:52 INFO - 'LOGNAME': 'cltbld', 18:16:52 INFO - 'MAIL': '/var/mail/cltbld', 18:16:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:52 INFO - 'MOZ_NO_REMOTE': '1', 18:16:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:52 INFO - 'NO_EM_RESTART': '1', 18:16:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:52 INFO - 'PWD': '/builds/slave/test', 18:16:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:52 INFO - 'SHELL': '/bin/bash', 18:16:52 INFO - 'SHLVL': '1', 18:16:52 INFO - 'TERM': 'linux', 18:16:52 INFO - 'TMOUT': '86400', 18:16:52 INFO - 'USER': 'cltbld', 18:16:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117', 18:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:52 INFO - '_': '/tools/buildbot/bin/python'} 18:16:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:16:52 INFO - mozversion application_buildid: 20161104153921 18:16:52 INFO - mozversion application_changeset: 3888f1a5702b34824b618b2110e09be7965477c5 18:16:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:16:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:52 INFO - mozversion application_name: Firefox 18:16:52 INFO - mozversion application_remotingname: firefox-dev 18:16:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:16:52 INFO - mozversion application_vendor: Mozilla 18:16:52 INFO - mozversion application_version: 51.0a2 18:16:52 INFO - mozversion platform_buildid: 20161104153921 18:16:52 INFO - mozversion platform_changeset: 3888f1a5702b34824b618b2110e09be7965477c5 18:16:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:16:52 INFO - mozversion platform_version: 51.0a2 18:16:52 INFO - using testdate: 1478308612 18:16:52 INFO - actual date: 1478308612 18:16:52 INFO - starting webserver on 'localhost:52466' 18:16:52 INFO - SUITE-START | Running 2 tests 18:16:52 INFO - TEST-START | dromaeo_css 18:16:52 INFO - operating with platform_type : linux_ 18:16:52 INFO - Initialising browser for dromaeo_css test... 18:16:52 INFO - TEST-INFO | started process 2883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEkoyVJ/profile http://localhost:52466/getInfo.html) 18:16:57 INFO - PROCESS | 2883 | __metrics Screen width/height:1600/1200 18:16:57 INFO - PROCESS | 2883 | colorDepth:24 18:16:57 INFO - PROCESS | 2883 | Browser inner width/height: 1024/697 18:16:57 INFO - PROCESS | 2883 | __metrics 18:16:57 INFO - PROCESS | 2883 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:16:57 INFO - PROCESS | 2883 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:16:58 INFO - PROCESS | 2883 | console.error: PushService: 18:16:58 INFO - PROCESS | 2883 | stateChangeProcessEnqueue: Error transitioning state 18:16:58 INFO - PROCESS | 2883 | UnknownError 18:16:58 INFO - TEST-INFO | 2883: exit 0 18:16:58 INFO - Browser initialized. 18:16:58 INFO - Running cycle 1/1 for dromaeo_css test... 18:16:58 INFO - TEST-INFO | started process 2998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEkoyVJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:10 INFO - PROCESS | 2998 | RSS: Main: 145141760 18:17:10 INFO - PROCESS | 2998 | 18:18:01 INFO - PROCESS | 2998 | 1478308681719 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] 18:20:11 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52466/tests/dromaeo/cssquery-ext.html) 18:20:12 INFO - PROCESS | 2998 | RSS: Main: 243200000 18:20:12 INFO - PROCESS | 2998 | 18:23:14 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-ext.html (next: http://localhost:52466/tests/dromaeo/cssquery-jquery.html) 18:23:14 INFO - PROCESS | 2998 | RSS: Main: 224030720 18:23:14 INFO - PROCESS | 2998 | 18:26:16 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52466/tests/dromaeo/cssquery-mootools.html) 18:26:17 INFO - PROCESS | 2998 | RSS: Main: 229019648 18:26:17 INFO - PROCESS | 2998 | 18:29:19 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52466/tests/dromaeo/cssquery-prototype.html) 18:29:20 INFO - PROCESS | 2998 | RSS: Main: 173481984 18:29:20 INFO - PROCESS | 2998 | 18:32:22 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52466/tests/dromaeo/cssquery-yui.html) 18:32:23 INFO - PROCESS | 2998 | RSS: Main: 177741824 18:32:23 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | Cycle 1(1): loaded http://localhost:52466/tests/dromaeo/cssquery-yui.html (next: http://localhost:52466/tests/dromaeo/cssquery-dojo.html) 18:35:25 INFO - PROCESS | 2998 | RSS: Main: 256925696 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | __start_tp_report 18:35:25 INFO - PROCESS | 2998 | _x_x_mozilla_page_load 18:35:25 INFO - PROCESS | 2998 | _x_x_mozilla_page_load_details 18:35:25 INFO - PROCESS | 2998 | |i|pagename|runs| 18:35:25 INFO - PROCESS | 2998 | |0;dojo.html;3105.5652208690785;3174.3016536362;3177.062766483887;3200.9679903200963;3224.484082546793;5559.666420014802;5680.261565996419;5691.686957217351;5705.4294570542925;5726.770929162829;5654.547636189105;5710.5039236078255;5714.25714657094;5720.827230417764;5759.049756790125;5150.072986862373;5179.974100129513;5189.299444574972;5214.921776173361;5256.92114618281;4999.9500004999845;5001.049800537904;5275;5281.445448227938;5312.229726689628;5121.155009423441;5124.461931497195;5126.948730512703;5163.0190263849745;5168.741562921858;5175.327207463025;5185.507376799217;5251.054810134175;5260.710660913648;5276.1030624793875;4939.604831613498;5089.109405853961;5161.767720452589;5211.218317252405;5216.426193118754;5433.945660543383;5508.834734957958;5512.5589952803675;5537.889242215133;5585.748641311151;5937.376575459601;5939.792107276225;6029.54778391618;6033.064874944347;6036.9396306036815;5854.092615644539;6069.241344831896;6079.361667024943;6088.391160883921;6091;5512.173174023888;5531.557475401958;5583.302087239135;5587.329520457572;5613.129964855454;6132.724027418688;6136.509079273647;6137.171481849982;6137.9079313810325;6173.074038894112;5667.489925906688;5877.294724632986;5877.5591830612875;5878.177055212273;5878.941210587925;5809.82570522885;5845.678487683218;5893.734781934823;5897.823065308047;5993.041113421831;5912.438318359749;5922.437368449991;5924.259467566554;5926.348101708809;5926.466618004314;5882.147088672119;5949.405059494016;5951.4941229995065;5956.493698035629;5977.67122808241;4456.331550267486;4504.166729155051;4507.166174257773;4564.064366804797;4569.702969306984;4043.332850079704;4069.0234343757284;4073.103917138225;4099.1186894817165;4106.384042393605;5619.43805619443;5623;5628.09950407941;5636.154576813511;5664.631798933056;5820.970895145497;5837.182794408786;5842.182094506772;5878.3239927408895;5957.344692083867;5639.351474580476;5645.604807663424;5651.124075768263;5671.886562268732;5684.8294551163535;5073.797048118034;5080.695158290514;5084.898302033938;5090.2364645303505;5118.181091025418;5949.702514874326;6069.180660610849;6093.482054025458;6202.689865506707;6218.22272215973;5812.9709351452975;5926.259217597801;5936.673482958306;5961.880762384815;5967.9701601491715;6145.631262124287;6198.6280823150755;6202.4417802398;6202.937970620236;6205.81382558524;5619.971900140473;5892.292924849045;5894.2926848778425;5894.616849904743;5907.645541267366;5824.271966004249;5939.732712028055;5992.460678538952;5993.820185394445;6105.877882442415;5870.236869207147;5918.822435326947;5919.4080591940465;5920.2895652521975;5922.082077277856;5847.239858818326;5905;5910.320313164041;5911.408859114054;5915.556333275108;5612.186232996274;5629.887402252014;5751.712414379181;5763.221965034667;5787;5898.852528686817;5904.232449781501;5911.497522710618;5911.704414779158;5920.615160014585;5553.111502159635;5603.271574695264;5720.056190728483;5742.1673857289625;5753.338366087954 18:35:25 INFO - PROCESS | 2998 | |1;ext.html;597.9551533634976;625.8091282158941;636.3890664961637;647.5143642268299;653.9313372095932;35784.463233051494;38206.23587528248;47534;48642.51357486424;48673;47826.28260576094;47883.99999999991;48068.75965620167;48120.75939620306;48240;8373.204545568191;9219.815603687923;9400.294977876652;9407.952960235189;9410.764730881749;8356.498610083385;10944.56221751129;11218.382988935606;11245.718857028598;11550.595729149481;11294;11362.261453005529;11379.317240965527;11379.715507112305;11411.714707132305;11169.72075698106;11221.775564488746;11223.943880280627;11237.3257604544;11239.438028098562;11099;11155.442227888654;11162.274452160666;11163.83254251187;11163.888361116447;45150.548494514966;45276.773616132035;45399;45472.09055818898;45485.545144548465;3634.9818250908575;3807.7905715185652;3812.8856134316015;3920.372740361557;4384.649228061748;33883;34135.48796768051;34403.655963440295;34646.82676586626;34856.82571587151;4454.153710794971;4485.327200919888;4509.436320459942;4519.706219095749;4554.203014472454;367133;367559;368606;368958;370961;63408;64047;64071.67964160149;64127.35872641307;64279;40483;42016.78991605084;42104.57895421007;43910;67606.323936761;17965.281388744304;18128.818711812975;18140.365087221882;18143.546411339557;18150.3647372344;12146.696332591579;12374.195677280948;13549.187048777105;13669.521566745116;13669.658258543615;20114.295999639944;20787.79212207889;20841.37475875726;21544.13823447076;21594;7197.388222001084;7418.295261950106;7600.125985511737;7615.200403957505;7646.158922518519;968.6706519783307;978.3982850546862;1156.6934762288001;1170.6897672116725;1187.709011292239;33783;34032.14919627029;34154;34292.31415371678;34653.1336716579;1102.691246450979;1118.7370967822555;1124.454639499842;1149.8160294352863;1162.8953394194564;17462.12689365552;17519.912400437915;17636.735448968277;17743.467695969142;17859.46421607354;574416;582803;585106;585434;586629;64112.67943660345;64547;64685.67657161684;64711.67644161843;64806;1279.5649479176939;1302.5961951794975;1348.3932230496316;1406.0579411794122;1428.607133038402;31779.841100795275;35462.645373545936;35973.46039809353;36423.81788091043;36433.63566364408;53370.73314633402;53619.46380536145;53966.46033539614;53989.460105400016;54039.45960540344;412222;415466;416005;416486;416553;31734.52398214075;32088.197795055312;32955;33181.66818331883;33270;50745.45490592603;50904.23643645422;51161.48838511567;51638.22542661788;51936;42443.57556424396;42550.78724606357;42779;42824;44277.11445771002;43306.70079897608;43609.56390436182;43758.343624845016;44451.33323000093;44674.3298850524;42953;43853.56146438495;44250.55749442464;44348.11303773842;44388.112237754416;36519.45220821637;38748.41877371785;39008.609913900495;39419.80290098646;39525.40711889266;29188.562171567868;29629.813418022855;29935.55096673508;30036.699633004264;30054.549181761853 18:35:25 INFO - PROCESS | 2998 | |2;jquery.html;394.8617983705705;419.17631853408045;429.1138798381342;431.49946062567426;440.7003237798296;15513.457029003986;19554.90222548887;20008.299709510153;20779.27272545461;20955.476113097182;16943.322267109303;18038.368657097006;18144;18158.45524634263;19816.207351705914;5787.33445653751;6072.574919755619;6079.544034197431;6080.695965201744;6081.118237855509;4299.957000429987;5500.834974950758;5984.551158663117;6727.360900714449;6744.055832183498;6693.263740988487;6697.430718388943;6704.798856034302;6704.932950670504;6705.597664140168;5866.853328666774;6183.474404675608;6191.876162476748;6279.780207692755;6281.371862813728;6071.605345652519;6075.574709770318;6081.939180608181;6085.726142323606;6085.9695701521205;15148.848511514854;15279.770803437958;17294.962302261778;24141.275761727175;24652.383690407703;2485.5526005319034;2621.6329713840078;2676.8795404206858;2685.825421347606;2809.8033137680372;10677.626283080133;10957.06864916483;11118.388488633122;11197.384143872085;11202;1936.883786972786;2021.9090140943545;2023.0794988280297;2057.855950083495;2097.538541520863;83621.16378836255;84970.57514712449;86199;86678.56660716656;86733;10456;11567.826482602768;11737.413129343498;11790.174687771774;12104.57633982824;15365.231738413258;15563.377464901503;16510.091944942902;16541.338346466007;17182.484525464257;8834.249088827522;8845.203931646183;8885.866711999326;8980.86528702057;9063.320250981204;8503.447275927045;8525.40322177454;8541;8751.693690720795;8755.255803256794;5070.112730272188;5132.384113906355;5137.588992880561;5172.612054095958;5292.100342941709;2048.8463365247667;2076.771555128935;2114.9471263218543;2119.4913220827198;2130.265058554827;887.2281115429489;971.223021582731;989.5992123189975;998.4059484606647;1072.3940973350032;20338.288159914333;20426.59146817085;20436.38690839277;20442.795572044088;20494.38516844497;964.2141654551518;971.8056388722284;994.9054839790208;996.740847379672;1008.848672699101;10555.97033497583;11241.21311508203;11378.43107844621;11392.316461012366;11630.127740419503;501626;507594;519524;525816;533172;105533.47233263784;110139.44930275297;111424;111942.44028779805;113817.43091284491;1024.329064462778;1041.2919214933765;1085.9076978456762;1088.4394536813547;1090.0516550601028;18447.63104737871;18813.153408096943;18851.811481885004;18988.905055474635;19305.142107433912;45924.311135333664;46049;47108.52891471041;47757;48165.518344816104;64084.67957660182;64233;64478.67760661354;64512;65849.67075164593;9082.727518174466;9112.817743645222;9373;9506.144446999526;9526.90473095288;11076.723081923017;14049;14306.785398219243;14418;14591.854081459049;11894.464749086485;11897.345645989553;12054.276743395425;12174.269543827399;12752.130770576547;7439.51643143194;11910.880891190976;12018.819717704417;12309.69225769327;12677.239365638092;12306.446209920396;12348.876511235132;12426.316552589333;12541.874581254435;12553.184043037174;9202.723918282461;9246.445213287225;9488;9611.423314601147;9664.806703865743;10448.898294535044;12123.515059397878;12663.620091397273;12668.746625067632;12952.093353465167 18:35:25 INFO - PROCESS | 2998 | |3;mootools.html;1391.7286129204808;1519.7188520123775;1563.437162621456;1685.401682402747;1798.6942219822638;8637.78405539861;9339.90660093399;9820.901790982089;9837.114659680636;10083.44541050242;9802.705918822428;9868.506574671273;9868.950655246714;9869.457179855106;9870.161036311922;2577.2783620586265;2602.726713695063;2603.1799983005294;2609.504194203105;2611.0730690604846;2505.6366826810104;2596.350912271932;2596.4158064435446;2597.4025974025985;2602.947941041178;2543.2370288913253;2557;2573.4981678572685;2579.6001619748968;2581.1224183777513;2450.718167410753;2452.828302018859;2499.3126890105186;2545.9236222913155;2551.3749131462723;2377.3343463830156;2409.8192635552405;2456.582380995217;2481.416867036245;2537.517871604408;9463.38487998278;9782.51087445625;9805.117539421486;9924.851127233098;9926.9503652482;5662.546996240291;5858.384869588717;5923.200367950315;5929.6145750526075;5949.9702501487645;3729.962700372989;3767.585565587783;3801.6198380161763;3870.748401353903;3882.0877093882914;1271.3833790611545;1274.4201388368363;1274.8151518029927;1307.928063956482;1319.9538016169483;13806.240656763874;13895.79156312656;13966.720665586632;14044.789328160086;14073.36669849849;4986.4514903360605;5137.12668846297;5267.157254839208;5311.760970756325;5362.597805164628;11805.173637845268;11805.645830625095;11812;11817.231879927776;11829.822552661717;5823.679697616672;5892.823215303548;5900.321463031806;5913.526917846562;5920.79277225295;4775.617950563947;4884.7069175849565;5327.786888524415;5372.785088596413;5425.050616142159;4403.427554417969;4557.908841823145;4590.655700822452;4625.236835922103;4637.07258548285;1410.6684929041667;1411.5483045425572;1447.5657302809116;1448.521987744063;1460.291758497128;812.7317985064915;837.4332670365433;916.4043371808244;1026.8767747870154;1033.0630068621335;6240.812775616739;6299.401556852092;6451.3226111257645;6508.316626754123;6564.606123632598;968.0107379335393;1011.7571782772081;1017.7201269650906;1029.145808978546;1059.195011791029;4155.91688166235;4335.241332766816;4342.544032876565;4356.3683265926265;4369.868903932887;12413.44139513724;12683.619491415273;12744.553940612033;12760.936195318964;12791.232526048467;12300;12471.31407772563;12617.990560755117;12629.74740505203;12640.241585504898;9099.727008189764;9169.08309169091;9181.495017773954;9225.446473211628;9242.12199841014;6110.7861224856915;6210;6304.621722696653;6357.396047375677;6404.711787969646;11209.943950280523;11456.94271528637;11490.597829075943;11523.481443335239;11552.440420543118;11191.216614837209;11215.55137794479;11339.886601134212;11605.187636865337;11662.416879155839;8227.629756660845;8400.117987611213;8406.87389689143;8412.411131220755;8415.116412776815;9361.906380936376;9527.237820974444;9532.094451027142;9538.046195380406;9650.855237171307;8356.582170891552;8588.098249683693;8712.738617841474;8844.071372506052;8919.509426981318;8445.451045682257;8486.575671216538;8538.40231183836;8652.78368040804;8818.206361427503;7447.441441891771;7463.029806125169;7527.962360188164;7538.359239464707;7619.31426171625;5133.537981581526;5305.575553955597;5383.569314454756;5481;5601.991641504568;5402.864928376822;5476.397596264487;5591.496765291144;5675.716214189191;5733.140028995517 18:35:25 INFO - PROCESS | 2998 | |4;prototype.html;345.4662546365864;351.25183359444395;353.1788591524706;355.03784743345534;355.6887723242165;5582.8604284892845;5602.271704678391;5602.991461536922;5631.662100273981;5662.745176467059;5540.2243685884005;5593.552515798737;5603.635763675368;5604.663720176793;5615.438456154383;4177.7284476509;4217.072244106292;4250.107477429743;4297.548757380477;4407.515173330931;4358.5423530529315;4358.673099517533;4377.496587892385;4402.908618406247;4435.51209366971;4317.438732964726;4322.4164737760475;4330.84842030529;4336.436263285768;4351.4343135392355;4099.918001639965;4104.651104656108;4267.893302667459;4337.414449049369;4350.412694286262;3800.277947190025;3813.180166264239;3824.521934758155;3827.7129215308687;3830.885073447801;5417.214503896913;5422.43064478228;5451.48210919962;5472.26124473195;5481.725913704299;4819.44576373718;4926.950730492685;4953.529414705597;4964.585093248443;4965;5711.942880571182;5719.828405147852;5740.684262365568;5756.424357564252;5781.88436231273;4246.174145117629;4256.148770245933;4257.531671516131;4270.914581708348;4281.850135245251;5669.688167150805;6001.789937352171;6010.729517171694;6018.518518518508;6024.1566180734735;4291.206126866539;4297.269464191095;4302.9569704303185;4310.6335961443;4321.848735294312;4383.9342409863875;4399.1641588098155;4399.890002749957;4400.361947517592;4412.161689279027;4768.593264986837;4934.827281045146;4939.53074457926;4944.258361245842;4968.428630707443;5870.530357571384;5891.410858914074;5901.468867801918;5903.35063143054;5915.201447804491;1433.2475450388629;1499.4601943300404;1503.2634009335347;1511.5465360391838;1522.8045983902584;934.1359242700474;955.1690029674228;969.2004096620317;978.4178413843752;997.7705127820642;5439.891202175934;5602.943970560324;5606.299212598426;5611.390703322974;5622.409646987007;5879.235699359056;5887.970560147258;5915.970420147872;5930.733117009745;5954.71973525694;5503.3120859892515;5548.973439913548;5577.8844231153935;5606.6916319602;5618;3117.4076925384106;3117.7349925256153;3120.9219769505944;3123.6407813101328;3126.812391256532;6034.668093254825;6096.037575575911;6137.864495068427;6148.846278843064;6167.414095660905;6031.909521357183;6047.244094488189;6064.151018857364;6065.757369705162;6067.693107256556;6101.755929762849;6111.54163437735;6113.57204995655;6121.204243448323;6173.32093469709;5673.319201695658;5909.970450147722;5937.139114828412;5973.5519836013345;6009.158717779602;5837.67892765885;5861.35525092248;5897.262842144732;5898.407429993963;5906.704664766658;5906.616069955439;6171.537134714825;6183.474404675473;6226.159468471879;6226.221722284715;4315.4605674290715;4320.358263859714;4345.413369195118;4361.6074553290355;4369.868903932887;6042.244719410074;6049.818505444844;6072.848178795565;6080.4831589313635;6101.572889897665;5691.3170419549915;5836;5863.560232982458;5865.589408741518;5938.376470470538;5860.736266868086;5863.355030946678;5864.67744274069;5893.263342082239;5897.938371093072;5659.122835960433;5850.561207909339;5883.441073098049;5890.143280508864;5898.675572843535;5833.008388574038;5885.146653735099;5895.4399332063385;5899.52220067564;5902.1736956826935;5730.398308177581;5738.938296415176;5765.798197063083;5782.421757824184;5823.330317013598 18:35:25 INFO - PROCESS | 2998 | |5;yui.html;798.8681867491864;820.1839170025821;824.9051359093706;892.8794612727282;901.1844280925757;16446.753298700514;17980.190891409886;18181.909090454563;18183.363582274622;18186.818131818676;14611.561653150422;16241.350345986177;16369.345226190968;16424.178791060458;16425.753613695804;829.2205326992623;841.6423020216414;841.970531031414;842.2419822160074;842.9536375499345;848.4315508609216;851.6465666748292;852.4288726553224;852.6887685994599;853.0573716043775;895.3240303570827;899.8335307968047;899.9550022498881;900.1943260781596;900.9279257659405;891.269159289383;892.6652505310509;892.9241014513742;893.2496702769672;893.5085702863431;659.3274859643137;660.7125900233414;660.7951535024158;660.9008648702685;661.1834384535096;14072.577822665336;14837.777433338508;14879.70240595182;14954;15065.322060507304;14185.716285674229;14474;14570;14593.78109328361;14817;12655.683607909783;12786;12849.67875803103;12852;13059.542915997894;4052.898677533056;4107.404426358161;4129.236091323085;4135.442686650028;4268.317069268902;83625.16374836235;85540;86183;86444.56777716134;86659.56670216672;10955.945220274007;11003.724906877393;11023.17326200538;11108.111351091893;11337.716557085974;22187.778122218893;22230.777692223193;22237.22169724052;22238.444038898833;22260.777392226195;8747.518886461306;9186.40288381253;9262.397944133745;9411.858822117674;9448.858267126;5172.482751724872;5263.789448422097;5336.599755018389;5423.593230507728;5430.294061771944;3689.6494832990825;3804.372278574004;3812.3519001769764;3840.577536470986;3841.8463261469788;553.9335279766374;556.1435389500206;556.2046273828383;559.3483591615803;572.4189947203591;738.1732459645125;746.8356961468468;757.4357103957586;775.3680750188578;776.3245976000916;10400.68371625782;10454.163666906628;10684.57261709523;11108.944455277671;11126.833097503544;681.5569879578313;687.3264201082867;754.1591125894588;783.6865253898486;820.1757233979812;4993.076280888047;5057.747112644427;5060.84817455477;5072.746362681851;5198.2722418861385;73595.63202184062;73628;73886;105757.47121264345;130297;63595.36404635987;88833;90792.54603727072;91134;105206;54907;56439;62939.6853015732;79050.60474697589;93799.53100234592;10554.618513741503;10581.576736930436;10604.681859544226;10645.89354106449;10717;51642;51965.7401712989;52394;52890.206646899554;53824.46175538195;73058.63470682825;74730.25269747233;79911.60044199742;79940.60029699815;80432.19567804247;14487.20320382389;14610.853891460949;14671.266436678337;14697.55907322782;14732.705345893237;15493.14787686688;15991.680166396374;16125.038687713008;16395.180240987665;16743.413980510624;14075.718485630436;14620.488282910048;14951.850481495481;15034.774478383051;15162.772558411853;13951.58145255644;14579.271036448346;14895.329710163285;15089.113860767404;15175.924120379328;14624.268786560842;14752.188629625474;14915.32881020335;14997.925010374878;15322.080675159526;11736.706582335168;11882.82175767347;12225.69435764113;12300.50797968107;12456.43946022413;12090.939545302217;12187.878121218675;12258.632241032783;12441.129120961446;12475 18:35:25 INFO - PROCESS | 2998 | __end_tp_report 18:35:25 INFO - PROCESS | 2998 | __start_cc_report 18:35:25 INFO - PROCESS | 2998 | _x_x_mozilla_cycle_collect,867 18:35:25 INFO - PROCESS | 2998 | __end_cc_report 18:35:25 INFO - PROCESS | 2998 | __startTimestamp1478309725841__endTimestamp 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | ------- Summary: start ------- 18:35:25 INFO - PROCESS | 2998 | Number of tests: 6 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#0] dojo.html Cycles:165 Average:5553.16 Median:5751.71 stddev:626.85 (10.9%) stddev-sans-first:598.64 18:35:25 INFO - PROCESS | 2998 | Values: 3105.6 3174.3 3177.1 3201.0 3224.5 5559.7 5680.3 5691.7 5705.4 5726.8 5654.5 5710.5 5714.3 5720.8 5759.0 5150.1 5180.0 5189.3 5214.9 5256.9 5000.0 5001.0 5275.0 5281.4 5312.2 5121.2 5124.5 5126.9 5163.0 5168.7 5175.3 5185.5 5251.1 5260.7 5276.1 4939.6 5089.1 5161.8 5211.2 5216.4 5433.9 5508.8 5512.6 5537.9 5585.7 5937.4 5939.8 6029.5 6033.1 6036.9 5854.1 6069.2 6079.4 6088.4 6091.0 5512.2 5531.6 5583.3 5587.3 5613.1 6132.7 6136.5 6137.2 6137.9 6173.1 5667.5 5877.3 5877.6 5878.2 5878.9 5809.8 5845.7 5893.7 5897.8 5993.0 5912.4 5922.4 5924.3 5926.3 5926.5 5882.1 5949.4 5951.5 5956.5 5977.7 4456.3 4504.2 4507.2 4564.1 4569.7 4043.3 4069.0 4073.1 4099.1 4106.4 5619.4 5623.0 5628.1 5636.2 5664.6 5821.0 5837.2 5842.2 5878.3 5957.3 5639.4 5645.6 5651.1 5671.9 5684.8 5073.8 5080.7 5084.9 5090.2 5118.2 5949.7 6069.2 6093.5 6202.7 6218.2 5813.0 5926.3 5936.7 5961.9 5968.0 6145.6 6198.6 6202.4 6202.9 6205.8 5620.0 5892.3 5894.3 5894.6 5907.6 5824.3 5939.7 5992.5 5993.8 6105.9 5870.2 5918.8 5919.4 5920.3 5922.1 5847.2 5905.0 5910.3 5911.4 5915.6 5612.2 5629.9 5751.7 5763.2 5787.0 5898.9 5904.2 5911.5 5911.7 5920.6 5553.1 5603.3 5720.1 5742.2 5753.3 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#1] ext.html Cycles:180 Average:62891.71 Median:21670.09 stddev:123145.62 (568.3%) stddev-sans-first:123402.26 18:35:25 INFO - PROCESS | 2998 | Values: 598.0 625.8 636.4 647.5 653.9 35784.5 38206.2 47534.0 48642.5 48673.0 47826.3 47884.0 48068.8 48120.8 48240.0 8373.2 9219.8 9400.3 9408.0 9410.8 8356.5 10944.6 11218.4 11245.7 11550.6 11294.0 11362.3 11379.3 11379.7 11411.7 11169.7 11221.8 11223.9 11237.3 11239.4 11099.0 11155.4 11162.3 11163.8 11163.9 45150.5 45276.8 45399.0 45472.1 45485.5 3635.0 3807.8 3812.9 3920.4 4384.6 33883.0 34135.5 34403.7 34646.8 34856.8 4454.2 4485.3 4509.4 4519.7 4554.2 367133.0 367559.0 368606.0 368958.0 370961.0 63408.0 64047.0 64071.7 64127.4 64279.0 40483.0 42016.8 42104.6 43910.0 67606.3 17965.3 18128.8 18140.4 18143.5 18150.4 12146.7 12374.2 13549.2 13669.5 13669.7 20114.3 20787.8 20841.4 21544.1 21594.0 7197.4 7418.3 7600.1 7615.2 7646.2 968.7 978.4 1156.7 1170.7 1187.7 33783.0 34032.1 34154.0 34292.3 34653.1 1102.7 1118.7 1124.5 1149.8 1162.9 17462.1 17519.9 17636.7 17743.5 17859.5 574416.0 582803.0 585106.0 585434.0 586629.0 64112.7 64547.0 64685.7 64711.7 64806.0 1279.6 1302.6 1348.4 1406.1 1428.6 31779.8 35462.6 35973.5 36423.8 36433.6 53370.7 53619.5 53966.5 53989.5 54039.5 412222.0 415466.0 416005.0 416486.0 416553.0 31734.5 32088.2 32955.0 33181.7 33270.0 50745.5 50904.2 51161.5 51638.2 51936.0 42443.6 42550.8 42779.0 42824.0 44277.1 43306.7 43609.6 43758.3 44451.3 44674.3 42953.0 43853.6 44250.6 44348.1 44388.1 36519.5 38748.4 39008.6 39419.8 39525.4 29188.6 29629.8 29935.6 30036.7 30054.5 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#2] jquery.html Cycles:180 Average:30996.02 Median:13568.97 stddev:85746.37 (631.9%) stddev-sans-first:85956.13 18:35:25 INFO - PROCESS | 2998 | Values: 394.9 419.2 429.1 431.5 440.7 15513.5 19554.9 20008.3 20779.3 20955.5 16943.3 18038.4 18144.0 18158.5 19816.2 5787.3 6072.6 6079.5 6080.7 6081.1 4300.0 5500.8 5984.6 6727.4 6744.1 6693.3 6697.4 6704.8 6704.9 6705.6 5866.9 6183.5 6191.9 6279.8 6281.4 6071.6 6075.6 6081.9 6085.7 6086.0 15148.8 15279.8 17295.0 24141.3 24652.4 2485.6 2621.6 2676.9 2685.8 2809.8 10677.6 10957.1 11118.4 11197.4 11202.0 1936.9 2021.9 2023.1 2057.9 2097.5 83621.2 84970.6 86199.0 86678.6 86733.0 10456.0 11567.8 11737.4 11790.2 12104.6 15365.2 15563.4 16510.1 16541.3 17182.5 8834.2 8845.2 8885.9 8980.9 9063.3 8503.4 8525.4 8541.0 8751.7 8755.3 5070.1 5132.4 5137.6 5172.6 5292.1 2048.8 2076.8 2114.9 2119.5 2130.3 887.2 971.2 989.6 998.4 1072.4 20338.3 20426.6 20436.4 20442.8 20494.4 964.2 971.8 994.9 996.7 1008.8 10556.0 11241.2 11378.4 11392.3 11630.1 501626.0 507594.0 519524.0 525816.0 533172.0 105533.5 110139.4 111424.0 111942.4 113817.4 1024.3 1041.3 1085.9 1088.4 1090.1 18447.6 18813.2 18851.8 18988.9 19305.1 45924.3 46049.0 47108.5 47757.0 48165.5 64084.7 64233.0 64478.7 64512.0 65849.7 9082.7 9112.8 9373.0 9506.1 9526.9 11076.7 14049.0 14306.8 14418.0 14591.9 11894.5 11897.3 12054.3 12174.3 12752.1 7439.5 11910.9 12018.8 12309.7 12677.2 12306.4 12348.9 12426.3 12541.9 12553.2 9202.7 9246.4 9488.0 9611.4 9664.8 10448.9 12123.5 12663.6 12668.7 12952.1 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#3] mootools.html Cycles:180 Average:6452.75 Median:4631.15 stddev:3764.92 (81.3%) stddev-sans-first:3756.27 18:35:25 INFO - PROCESS | 2998 | Values: 1391.7 1519.7 1563.4 1685.4 1798.7 8637.8 9339.9 9820.9 9837.1 10083.4 9802.7 9868.5 9869.0 9869.5 9870.2 2577.3 2602.7 2603.2 2609.5 2611.1 2505.6 2596.4 2596.4 2597.4 2602.9 2543.2 2557.0 2573.5 2579.6 2581.1 2450.7 2452.8 2499.3 2545.9 2551.4 2377.3 2409.8 2456.6 2481.4 2537.5 9463.4 9782.5 9805.1 9924.9 9927.0 5662.5 5858.4 5923.2 5929.6 5950.0 3730.0 3767.6 3801.6 3870.7 3882.1 1271.4 1274.4 1274.8 1307.9 1320.0 13806.2 13895.8 13966.7 14044.8 14073.4 4986.5 5137.1 5267.2 5311.8 5362.6 11805.2 11805.6 11812.0 11817.2 11829.8 5823.7 5892.8 5900.3 5913.5 5920.8 4775.6 4884.7 5327.8 5372.8 5425.1 4403.4 4557.9 4590.7 4625.2 4637.1 1410.7 1411.5 1447.6 1448.5 1460.3 812.7 837.4 916.4 1026.9 1033.1 6240.8 6299.4 6451.3 6508.3 6564.6 968.0 1011.8 1017.7 1029.1 1059.2 4155.9 4335.2 4342.5 4356.4 4369.9 12413.4 12683.6 12744.6 12760.9 12791.2 12300.0 12471.3 12618.0 12629.7 12640.2 9099.7 9169.1 9181.5 9225.4 9242.1 6110.8 6210.0 6304.6 6357.4 6404.7 11209.9 11456.9 11490.6 11523.5 11552.4 11191.2 11215.6 11339.9 11605.2 11662.4 8227.6 8400.1 8406.9 8412.4 8415.1 9361.9 9527.2 9532.1 9538.0 9650.9 8356.6 8588.1 8712.7 8844.1 8919.5 8445.5 8486.6 8538.4 8652.8 8818.2 7447.4 7463.0 7528.0 7538.4 7619.3 5133.5 5305.6 5383.6 5481.0 5602.0 5402.9 5476.4 5591.5 5675.7 5733.1 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#4] prototype.html Cycles:180 Average:4914.28 Median:5616.72 stddev:1443.35 (25.7%) stddev-sans-first:1406.07 18:35:25 INFO - PROCESS | 2998 | Values: 345.5 351.3 353.2 355.0 355.7 5582.9 5602.3 5603.0 5631.7 5662.7 5540.2 5593.6 5603.6 5604.7 5615.4 4177.7 4217.1 4250.1 4297.5 4407.5 4358.5 4358.7 4377.5 4402.9 4435.5 4317.4 4322.4 4330.8 4336.4 4351.4 4099.9 4104.7 4267.9 4337.4 4350.4 3800.3 3813.2 3824.5 3827.7 3830.9 5417.2 5422.4 5451.5 5472.3 5481.7 4819.4 4927.0 4953.5 4964.6 4965.0 5711.9 5719.8 5740.7 5756.4 5781.9 4246.2 4256.1 4257.5 4270.9 4281.9 5669.7 6001.8 6010.7 6018.5 6024.2 4291.2 4297.3 4303.0 4310.6 4321.8 4383.9 4399.2 4399.9 4400.4 4412.2 4768.6 4934.8 4939.5 4944.3 4968.4 5870.5 5891.4 5901.5 5903.4 5915.2 1433.2 1499.5 1503.3 1511.5 1522.8 934.1 955.2 969.2 978.4 997.8 5439.9 5602.9 5606.3 5611.4 5622.4 5879.2 5888.0 5916.0 5930.7 5954.7 5503.3 5549.0 5577.9 5606.7 5618.0 3117.4 3117.7 3120.9 3123.6 3126.8 6034.7 6096.0 6137.9 6148.8 6167.4 6031.9 6047.2 6064.2 6065.8 6067.7 6101.8 6111.5 6113.6 6121.2 6173.3 5673.3 5910.0 5937.1 5973.6 6009.2 5837.7 5861.4 5897.3 5898.4 5906.7 5906.6 6171.5 6183.5 6226.2 6226.2 4315.5 4320.4 4345.4 4361.6 4369.9 6042.2 6049.8 6072.8 6080.5 6101.6 5691.3 5836.0 5863.6 5865.6 5938.4 5860.7 5863.4 5864.7 5893.3 5897.9 5659.1 5850.6 5883.4 5890.1 5898.7 5833.0 5885.1 5895.4 5899.5 5902.2 5730.4 5738.9 5765.8 5782.4 5823.3 18:35:25 INFO - PROCESS | 2998 | 18:35:25 INFO - PROCESS | 2998 | [#5] yui.html Cycles:180 Average:20263.59 Median:4132.34 stddev:27372.29 (662.4%) stddev-sans-first:27410.06 18:35:25 INFO - PROCESS | 2998 | Values: 798.9 820.2 824.9 892.9 901.2 16446.8 17980.2 18181.9 18183.4 18186.8 14611.6 16241.4 16369.3 16424.2 16425.8 829.2 841.6 842.0 842.2 843.0 848.4 851.6 852.4 852.7 853.1 895.3 899.8 900.0 900.2 900.9 891.3 892.7 892.9 893.2 893.5 659.3 660.7 660.8 660.9 661.2 14072.6 14837.8 14879.7 14954.0 15065.3 14185.7 14474.0 14570.0 14593.8 14817.0 12655.7 12786.0 12849.7 12852.0 13059.5 4052.9 4107.4 4129.2 4135.4 4268.3 83625.2 85540.0 86183.0 86444.6 86659.6 10955.9 11003.7 11023.2 11108.1 11337.7 22187.8 22230.8 22237.2 22238.4 22260.8 8747.5 9186.4 9262.4 9411.9 9448.9 5172.5 5263.8 5336.6 5423.6 5430.3 3689.6 3804.4 3812.4 3840.6 3841.8 553.9 556.1 556.2 559.3 572.4 738.2 746.8 757.4 775.4 776.3 10400.7 10454.2 10684.6 11108.9 11126.8 681.6 687.3 754.2 783.7 820.2 4993.1 5057.7 5060.8 5072.7 5198.3 73595.6 73628.0 73886.0 105757.5 130297.0 63595.4 88833.0 90792.5 91134.0 105206.0 54907.0 56439.0 62939.7 79050.6 93799.5 10554.6 10581.6 10604.7 10645.9 10717.0 51642.0 51965.7 52394.0 52890.2 53824.5 73058.6 74730.3 79911.6 79940.6 80432.2 14487.2 14610.9 14671.3 14697.6 14732.7 15493.1 15991.7 16125.0 16395.2 16743.4 14075.7 14620.5 14951.9 15034.8 15162.8 13951.6 14579.3 14895.3 15089.1 15175.9 14624.3 14752.2 14915.3 14997.9 15322.1 11736.7 11882.8 12225.7 12300.5 12456.4 12090.9 12187.9 12258.6 12441.1 12475.0 18:35:25 INFO - PROCESS | 2998 | -------- Summary: end -------- 18:35:25 INFO - PROCESS | 2998 | 18:35:26 INFO - TEST-INFO | 2998: exit 0 18:35:26 INFO - TEST-OK | dromaeo_css | took 1114029ms 18:35:26 INFO - TEST-START | kraken 18:35:26 INFO - operating with platform_type : linux_ 18:35:26 INFO - Initialising browser for kraken test... 18:35:26 INFO - TEST-INFO | started process 3319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSGsJX/profile http://localhost:52466/getInfo.html) 18:35:30 INFO - PROCESS | 3319 | __metrics Screen width/height:1600/1200 18:35:30 INFO - PROCESS | 3319 | colorDepth:24 18:35:30 INFO - PROCESS | 3319 | Browser inner width/height: 1024/697 18:35:30 INFO - PROCESS | 3319 | __metrics 18:35:31 INFO - PROCESS | 3319 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:35:31 INFO - PROCESS | 3319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:35:32 INFO - PROCESS | 3319 | console.error: PushService: 18:35:32 INFO - PROCESS | 3319 | stateChangeProcessEnqueue: Error transitioning state 18:35:32 INFO - PROCESS | 3319 | UnknownError 18:35:32 INFO - TEST-INFO | 3319: exit 0 18:35:32 INFO - Browser initialized. 18:35:32 INFO - Running cycle 1/1 for kraken test... 18:35:32 INFO - TEST-INFO | started process 3411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSGsJX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:44 INFO - PROCESS | 3411 | RSS: Main: 144879616 18:35:44 INFO - PROCESS | 3411 | 18:35:46 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52466/tests/kraken/driver.html?testName=audio-beat-detection) 18:35:47 INFO - PROCESS | 3411 | RSS: Main: 186105856 18:35:47 INFO - PROCESS | 3411 | 18:35:50 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52466/tests/kraken/driver.html?testName=audio-dft) 18:35:50 INFO - PROCESS | 3411 | RSS: Main: 176054272 18:35:50 INFO - PROCESS | 3411 | 18:35:53 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52466/tests/kraken/driver.html?testName=audio-fft) 18:35:54 INFO - PROCESS | 3411 | RSS: Main: 267931648 18:35:54 INFO - PROCESS | 3411 | 18:35:56 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52466/tests/kraken/driver.html?testName=audio-oscillator) 18:35:56 INFO - PROCESS | 3411 | RSS: Main: 173416448 18:35:56 INFO - PROCESS | 3411 | 18:35:58 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52466/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:35:59 INFO - PROCESS | 3411 | RSS: Main: 175423488 18:35:59 INFO - PROCESS | 3411 | 18:36:02 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52466/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:03 INFO - PROCESS | 3411 | RSS: Main: 211124224 18:36:03 INFO - PROCESS | 3411 | 18:36:06 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52466/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:07 INFO - PROCESS | 3411 | RSS: Main: 209899520 18:36:07 INFO - PROCESS | 3411 | 18:36:09 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52466/tests/kraken/driver.html?testName=json-parse-financial) 18:36:10 INFO - PROCESS | 3411 | RSS: Main: 210108416 18:36:10 INFO - PROCESS | 3411 | 18:36:11 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52466/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:12 INFO - PROCESS | 3411 | RSS: Main: 171401216 18:36:12 INFO - PROCESS | 3411 | 18:36:14 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:14 INFO - PROCESS | 3411 | RSS: Main: 179806208 18:36:14 INFO - PROCESS | 3411 | 18:36:16 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:17 INFO - PROCESS | 3411 | RSS: Main: 188997632 18:36:17 INFO - PROCESS | 3411 | 18:36:19 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:20 INFO - PROCESS | 3411 | RSS: Main: 196685824 18:36:20 INFO - PROCESS | 3411 | 18:36:23 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:23 INFO - PROCESS | 3411 | RSS: Main: 193691648 18:36:23 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:52466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52466/tests/kraken/driver.html?testName=ai-astar) 18:36:25 INFO - PROCESS | 3411 | RSS: Main: 244203520 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | __start_tp_report 18:36:25 INFO - PROCESS | 3411 | _x_x_mozilla_page_load 18:36:25 INFO - PROCESS | 3411 | _x_x_mozilla_page_load_details 18:36:25 INFO - PROCESS | 3411 | |i|pagename|runs| 18:36:25 INFO - PROCESS | 3411 | |0;ai-astar;108;112;110;109;111;110;110;110;110;110 18:36:25 INFO - PROCESS | 3411 | |1;audio-beat-detection;143;116;115;163;116;122;116;116;155;120 18:36:25 INFO - PROCESS | 3411 | |2;audio-dft;160;159;160;159;159;158;159;160;160;160 18:36:25 INFO - PROCESS | 3411 | |3;audio-fft;74;76;75;115;76;76;74;75;105;74 18:36:25 INFO - PROCESS | 3411 | |4;audio-oscillator;81;81;126;82;81;80;100;82;82;111 18:36:25 INFO - PROCESS | 3411 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;116;117;117 18:36:25 INFO - PROCESS | 3411 | |6;imaging-darkroom;146;146;146;146;147;147;145;146;147;145 18:36:25 INFO - PROCESS | 3411 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 18:36:25 INFO - PROCESS | 3411 | |8;json-parse-financial;52;49;49;49;52;49;51;50;49;57 18:36:25 INFO - PROCESS | 3411 | |9;json-stringify-tinderbox;59;59;59;58;59;59;59;58;63;58 18:36:25 INFO - PROCESS | 3411 | |10;stanford-crypto-aes;73;73;73;73;73;74;81;72;73;74 18:36:25 INFO - PROCESS | 3411 | |11;stanford-crypto-ccm;121;122;131;131;122;121;118;117;162;122 18:36:25 INFO - PROCESS | 3411 | |12;stanford-crypto-pbkdf2;152;151;155;152;158;160;160;149;153;153 18:36:25 INFO - PROCESS | 3411 | |13;stanford-crypto-sha256-iterative;61;63;62;63;62;62;62;64;61;61 18:36:25 INFO - PROCESS | 3411 | __end_tp_report 18:36:25 INFO - PROCESS | 3411 | __start_cc_report 18:36:25 INFO - PROCESS | 3411 | _x_x_mozilla_cycle_collect,1601 18:36:25 INFO - PROCESS | 3411 | __end_cc_report 18:36:25 INFO - PROCESS | 3411 | __startTimestamp1478309785640__endTimestamp 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | ------- Summary: start ------- 18:36:25 INFO - PROCESS | 3411 | Number of tests: 14 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:1.05 (1.0%) stddev-sans-first:0.83 18:36:25 INFO - PROCESS | 3411 | Values: 108.0 112.0 110.0 109.0 111.0 110.0 110.0 110.0 110.0 110.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:121.00 stddev:18.33 (15.1%) stddev-sans-first:18.64 18:36:25 INFO - PROCESS | 3411 | Values: 143.0 116.0 115.0 163.0 116.0 122.0 116.0 116.0 155.0 120.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#2] audio-dft Cycles:10 Average:159.40 Median:160.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 18:36:25 INFO - PROCESS | 3411 | Values: 160.0 159.0 160.0 159.0 159.0 158.0 159.0 160.0 160.0 160.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:14.97 (20.0%) stddev-sans-first:15.59 18:36:25 INFO - PROCESS | 3411 | Values: 74.0 76.0 75.0 115.0 76.0 76.0 74.0 75.0 105.0 74.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:81.00 stddev:16.22 (20.0%) stddev-sans-first:16.83 18:36:25 INFO - PROCESS | 3411 | Values: 81.0 81.0 126.0 82.0 81.0 80.0 100.0 82.0 82.0 111.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:36:25 INFO - PROCESS | 3411 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 18:36:25 INFO - PROCESS | 3411 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 145.0 146.0 147.0 145.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:36:25 INFO - PROCESS | 3411 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.50 stddev:2.54 (5.0%) stddev-sans-first:2.65 18:36:25 INFO - PROCESS | 3411 | Values: 52.0 49.0 49.0 49.0 52.0 49.0 51.0 50.0 49.0 57.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:1.45 (2.5%) stddev-sans-first:1.54 18:36:25 INFO - PROCESS | 3411 | Values: 59.0 59.0 59.0 58.0 59.0 59.0 59.0 58.0 63.0 58.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.00 stddev:2.56 (3.5%) stddev-sans-first:2.69 18:36:25 INFO - PROCESS | 3411 | Values: 73.0 73.0 73.0 73.0 73.0 74.0 81.0 72.0 73.0 74.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:122.00 stddev:13.27 (10.9%) stddev-sans-first:13.91 18:36:25 INFO - PROCESS | 3411 | Values: 121.0 122.0 131.0 131.0 122.0 121.0 118.0 117.0 162.0 122.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:154.00 stddev:3.83 (2.5%) stddev-sans-first:3.97 18:36:25 INFO - PROCESS | 3411 | Values: 152.0 151.0 155.0 152.0 158.0 160.0 160.0 149.0 153.0 153.0 18:36:25 INFO - PROCESS | 3411 | 18:36:25 INFO - PROCESS | 3411 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:0.99 (1.6%) stddev-sans-first:0.97 18:36:25 INFO - PROCESS | 3411 | Values: 61.0 63.0 62.0 63.0 62.0 62.0 62.0 64.0 61.0 61.0 18:36:25 INFO - PROCESS | 3411 | -------- Summary: end -------- 18:36:25 INFO - PROCESS | 3411 | 18:36:26 INFO - TEST-INFO | 3411: exit 0 18:36:26 INFO - TEST-OK | kraken | took 59969ms 18:36:26 INFO - SUITE-END | took 1173s 18:36:26 INFO - Completed test suite (00:19:34) 18:36:26 INFO - javascript benchmark 18:36:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.5652208690785, 3174.3016536362, 3177.062766483887, 3200.9679903200963, 3224.484082546793, 5559.666420014802, 5680.261565996419, 5691.686957217351, 5705.4294570542925, 5726.770929162829, 5654.547636189105, 5710.5039236078255, 5714.25714657094, 5720.827230417764, 5759.049756790125, 5150.072986862373, 5179.974100129513, 5189.299444574972, 5214.921776173361, 5256.92114618281, 4999.9500004999845, 5001.049800537904, 5275.0, 5281.445448227938, 5312.229726689628, 5121.155009423441, 5124.461931497195, 5126.948730512703, 5163.0190263849745, 5168.741562921858, 5175.327207463025, 5185.507376799217, 5251.054810134175, 5260.710660913648, 5276.1030624793875, 4939.604831613498, 5089.109405853961, 5161.767720452589, 5211.218317252405, 5216.426193118754, 5433.945660543383, 5508.834734957958, 5512.5589952803675, 5537.889242215133, 5585.748641311151, 5937.376575459601, 5939.792107276225, 6029.54778391618, 6033.064874944347, 6036.9396306036815, 5854.092615644539, 6069.241344831896, 6079.361667024943, 6088.391160883921, 6091.0, 5512.173174023888, 5531.557475401958, 5583.302087239135, 5587.329520457572, 5613.129964855454, 6132.724027418688, 6136.509079273647, 6137.171481849982, 6137.9079313810325, 6173.074038894112, 5667.489925906688, 5877.294724632986, 5877.5591830612875, 5878.177055212273, 5878.941210587925, 5809.82570522885, 5845.678487683218, 5893.734781934823, 5897.823065308047, 5993.041113421831, 5912.438318359749, 5922.437368449991, 5924.259467566554, 5926.348101708809, 5926.466618004314, 5882.147088672119, 5949.405059494016, 5951.4941229995065, 5956.493698035629, 5977.67122808241, 4456.331550267486, 4504.166729155051, 4507.166174257773, 4564.064366804797, 4569.702969306984, 4043.332850079704, 4069.0234343757284, 4073.103917138225, 4099.1186894817165, 4106.384042393605, 5619.43805619443, 5623.0, 5628.09950407941, 5636.154576813511, 5664.631798933056, 5820.970895145497, 5837.182794408786, 5842.182094506772, 5878.3239927408895, 5957.344692083867, 5639.351474580476, 5645.604807663424, 5651.124075768263, 5671.886562268732, 5684.8294551163535, 5073.797048118034, 5080.695158290514, 5084.898302033938, 5090.2364645303505, 5118.181091025418, 5949.702514874326, 6069.180660610849, 6093.482054025458, 6202.689865506707, 6218.22272215973, 5812.9709351452975, 5926.259217597801, 5936.673482958306, 5961.880762384815, 5967.9701601491715, 6145.631262124287, 6198.6280823150755, 6202.4417802398, 6202.937970620236, 6205.81382558524, 5619.971900140473, 5892.292924849045, 5894.2926848778425, 5894.616849904743, 5907.645541267366, 5824.271966004249, 5939.732712028055, 5992.460678538952, 5993.820185394445, 6105.877882442415, 5870.236869207147, 5918.822435326947, 5919.4080591940465, 5920.2895652521975, 5922.082077277856, 5847.239858818326, 5905.0, 5910.320313164041, 5911.408859114054, 5915.556333275108, 5612.186232996274, 5629.887402252014, 5751.712414379181, 5763.221965034667, 5787.0, 5898.852528686817, 5904.232449781501, 5911.497522710618, 5911.704414779158, 5920.615160014585, 5553.111502159635, 5603.271574695264, 5720.056190728483, 5742.1673857289625, 5753.338366087954], "value": 5510.468089152514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9551533634976, 625.8091282158941, 636.3890664961637, 647.5143642268299, 653.9313372095932, 35784.463233051494, 38206.23587528248, 47534.0, 48642.51357486424, 48673.0, 47826.28260576094, 47883.99999999991, 48068.75965620167, 48120.75939620306, 48240.0, 8373.204545568191, 9219.815603687923, 9400.294977876652, 9407.952960235189, 9410.764730881749, 8356.498610083385, 10944.56221751129, 11218.382988935606, 11245.718857028598, 11550.595729149481, 11294.0, 11362.261453005529, 11379.317240965527, 11379.715507112305, 11411.714707132305, 11169.72075698106, 11221.775564488746, 11223.943880280627, 11237.3257604544, 11239.438028098562, 11099.0, 11155.442227888654, 11162.274452160666, 11163.83254251187, 11163.888361116447, 45150.548494514966, 45276.773616132035, 45399.0, 45472.09055818898, 45485.545144548465, 3634.9818250908575, 3807.7905715185652, 3812.8856134316015, 3920.372740361557, 4384.649228061748, 33883.0, 34135.48796768051, 34403.655963440295, 34646.82676586626, 34856.82571587151, 4454.153710794971, 4485.327200919888, 4509.436320459942, 4519.706219095749, 4554.203014472454, 367133.0, 367559.0, 368606.0, 368958.0, 370961.0, 63408.0, 64047.0, 64071.67964160149, 64127.35872641307, 64279.0, 40483.0, 42016.78991605084, 42104.57895421007, 43910.0, 67606.323936761, 17965.281388744304, 18128.818711812975, 18140.365087221882, 18143.546411339557, 18150.3647372344, 12146.696332591579, 12374.195677280948, 13549.187048777105, 13669.521566745116, 13669.658258543615, 20114.295999639944, 20787.79212207889, 20841.37475875726, 21544.13823447076, 21594.0, 7197.388222001084, 7418.295261950106, 7600.125985511737, 7615.200403957505, 7646.158922518519, 968.6706519783307, 978.3982850546862, 1156.6934762288001, 1170.6897672116725, 1187.709011292239, 33783.0, 34032.14919627029, 34154.0, 34292.31415371678, 34653.1336716579, 1102.691246450979, 1118.7370967822555, 1124.454639499842, 1149.8160294352863, 1162.8953394194564, 17462.12689365552, 17519.912400437915, 17636.735448968277, 17743.467695969142, 17859.46421607354, 574416.0, 582803.0, 585106.0, 585434.0, 586629.0, 64112.67943660345, 64547.0, 64685.67657161684, 64711.67644161843, 64806.0, 1279.5649479176939, 1302.5961951794975, 1348.3932230496316, 1406.0579411794122, 1428.607133038402, 31779.841100795275, 35462.645373545936, 35973.46039809353, 36423.81788091043, 36433.63566364408, 53370.73314633402, 53619.46380536145, 53966.46033539614, 53989.460105400016, 54039.45960540344, 412222.0, 415466.0, 416005.0, 416486.0, 416553.0, 31734.52398214075, 32088.197795055312, 32955.0, 33181.66818331883, 33270.0, 50745.45490592603, 50904.23643645422, 51161.48838511567, 51638.22542661788, 51936.0, 42443.57556424396, 42550.78724606357, 42779.0, 42824.0, 44277.11445771002, 43306.70079897608, 43609.56390436182, 43758.343624845016, 44451.33323000093, 44674.3298850524, 42953.0, 43853.56146438495, 44250.55749442464, 44348.11303773842, 44388.112237754416, 36519.45220821637, 38748.41877371785, 39008.609913900495, 39419.80290098646, 39525.40711889266, 29188.562171567868, 29629.813418022855, 29935.55096673508, 30036.699633004264, 30054.549181761853], "value": 21550.26203787671, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.8617983705705, 419.17631853408045, 429.1138798381342, 431.49946062567426, 440.7003237798296, 15513.457029003986, 19554.90222548887, 20008.299709510153, 20779.27272545461, 20955.476113097182, 16943.322267109303, 18038.368657097006, 18144.0, 18158.45524634263, 19816.207351705914, 5787.33445653751, 6072.574919755619, 6079.544034197431, 6080.695965201744, 6081.118237855509, 4299.957000429987, 5500.834974950758, 5984.551158663117, 6727.360900714449, 6744.055832183498, 6693.263740988487, 6697.430718388943, 6704.798856034302, 6704.932950670504, 6705.597664140168, 5866.853328666774, 6183.474404675608, 6191.876162476748, 6279.780207692755, 6281.371862813728, 6071.605345652519, 6075.574709770318, 6081.939180608181, 6085.726142323606, 6085.9695701521205, 15148.848511514854, 15279.770803437958, 17294.962302261778, 24141.275761727175, 24652.383690407703, 2485.5526005319034, 2621.6329713840078, 2676.8795404206858, 2685.825421347606, 2809.8033137680372, 10677.626283080133, 10957.06864916483, 11118.388488633122, 11197.384143872085, 11202.0, 1936.883786972786, 2021.9090140943545, 2023.0794988280297, 2057.855950083495, 2097.538541520863, 83621.16378836255, 84970.57514712449, 86199.0, 86678.56660716656, 86733.0, 10456.0, 11567.826482602768, 11737.413129343498, 11790.174687771774, 12104.57633982824, 15365.231738413258, 15563.377464901503, 16510.091944942902, 16541.338346466007, 17182.484525464257, 8834.249088827522, 8845.203931646183, 8885.866711999326, 8980.86528702057, 9063.320250981204, 8503.447275927045, 8525.40322177454, 8541.0, 8751.693690720795, 8755.255803256794, 5070.112730272188, 5132.384113906355, 5137.588992880561, 5172.612054095958, 5292.100342941709, 2048.8463365247667, 2076.771555128935, 2114.9471263218543, 2119.4913220827198, 2130.265058554827, 887.2281115429489, 971.223021582731, 989.5992123189975, 998.4059484606647, 1072.3940973350032, 20338.288159914333, 20426.59146817085, 20436.38690839277, 20442.795572044088, 20494.38516844497, 964.2141654551518, 971.8056388722284, 994.9054839790208, 996.740847379672, 1008.848672699101, 10555.97033497583, 11241.21311508203, 11378.43107844621, 11392.316461012366, 11630.127740419503, 501626.0, 507594.0, 519524.0, 525816.0, 533172.0, 105533.47233263784, 110139.44930275297, 111424.0, 111942.44028779805, 113817.43091284491, 1024.329064462778, 1041.2919214933765, 1085.9076978456762, 1088.4394536813547, 1090.0516550601028, 18447.63104737871, 18813.153408096943, 18851.811481885004, 18988.905055474635, 19305.142107433912, 45924.311135333664, 46049.0, 47108.52891471041, 47757.0, 48165.518344816104, 64084.67957660182, 64233.0, 64478.67760661354, 64512.0, 65849.67075164593, 9082.727518174466, 9112.817743645222, 9373.0, 9506.144446999526, 9526.90473095288, 11076.723081923017, 14049.0, 14306.785398219243, 14418.0, 14591.854081459049, 11894.464749086485, 11897.345645989553, 12054.276743395425, 12174.269543827399, 12752.130770576547, 7439.51643143194, 11910.880891190976, 12018.819717704417, 12309.69225769327, 12677.239365638092, 12306.446209920396, 12348.876511235132, 12426.316552589333, 12541.874581254435, 12553.184043037174, 9202.723918282461, 9246.445213287225, 9488.0, 9611.423314601147, 9664.806703865743, 10448.898294535044, 12123.515059397878, 12663.620091397273, 12668.746625067632, 12952.093353465167], "value": 9745.731605006109, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.7286129204808, 1519.7188520123775, 1563.437162621456, 1685.401682402747, 1798.6942219822638, 8637.78405539861, 9339.90660093399, 9820.901790982089, 9837.114659680636, 10083.44541050242, 9802.705918822428, 9868.506574671273, 9868.950655246714, 9869.457179855106, 9870.161036311922, 2577.2783620586265, 2602.726713695063, 2603.1799983005294, 2609.504194203105, 2611.0730690604846, 2505.6366826810104, 2596.350912271932, 2596.4158064435446, 2597.4025974025985, 2602.947941041178, 2543.2370288913253, 2557.0, 2573.4981678572685, 2579.6001619748968, 2581.1224183777513, 2450.718167410753, 2452.828302018859, 2499.3126890105186, 2545.9236222913155, 2551.3749131462723, 2377.3343463830156, 2409.8192635552405, 2456.582380995217, 2481.416867036245, 2537.517871604408, 9463.38487998278, 9782.51087445625, 9805.117539421486, 9924.851127233098, 9926.9503652482, 5662.546996240291, 5858.384869588717, 5923.200367950315, 5929.6145750526075, 5949.9702501487645, 3729.962700372989, 3767.585565587783, 3801.6198380161763, 3870.748401353903, 3882.0877093882914, 1271.3833790611545, 1274.4201388368363, 1274.8151518029927, 1307.928063956482, 1319.9538016169483, 13806.240656763874, 13895.79156312656, 13966.720665586632, 14044.789328160086, 14073.36669849849, 4986.4514903360605, 5137.12668846297, 5267.157254839208, 5311.760970756325, 5362.597805164628, 11805.173637845268, 11805.645830625095, 11812.0, 11817.231879927776, 11829.822552661717, 5823.679697616672, 5892.823215303548, 5900.321463031806, 5913.526917846562, 5920.79277225295, 4775.617950563947, 4884.7069175849565, 5327.786888524415, 5372.785088596413, 5425.050616142159, 4403.427554417969, 4557.908841823145, 4590.655700822452, 4625.236835922103, 4637.07258548285, 1410.6684929041667, 1411.5483045425572, 1447.5657302809116, 1448.521987744063, 1460.291758497128, 812.7317985064915, 837.4332670365433, 916.4043371808244, 1026.8767747870154, 1033.0630068621335, 6240.812775616739, 6299.401556852092, 6451.3226111257645, 6508.316626754123, 6564.606123632598, 968.0107379335393, 1011.7571782772081, 1017.7201269650906, 1029.145808978546, 1059.195011791029, 4155.91688166235, 4335.241332766816, 4342.544032876565, 4356.3683265926265, 4369.868903932887, 12413.44139513724, 12683.619491415273, 12744.553940612033, 12760.936195318964, 12791.232526048467, 12300.0, 12471.31407772563, 12617.990560755117, 12629.74740505203, 12640.241585504898, 9099.727008189764, 9169.08309169091, 9181.495017773954, 9225.446473211628, 9242.12199841014, 6110.7861224856915, 6210.0, 6304.621722696653, 6357.396047375677, 6404.711787969646, 11209.943950280523, 11456.94271528637, 11490.597829075943, 11523.481443335239, 11552.440420543118, 11191.216614837209, 11215.55137794479, 11339.886601134212, 11605.187636865337, 11662.416879155839, 8227.629756660845, 8400.117987611213, 8406.87389689143, 8412.411131220755, 8415.116412776815, 9361.906380936376, 9527.237820974444, 9532.094451027142, 9538.046195380406, 9650.855237171307, 8356.582170891552, 8588.098249683693, 8712.738617841474, 8844.071372506052, 8919.509426981318, 8445.451045682257, 8486.575671216538, 8538.40231183836, 8652.78368040804, 8818.206361427503, 7447.441441891771, 7463.029806125169, 7527.962360188164, 7538.359239464707, 7619.31426171625, 5133.537981581526, 5305.575553955597, 5383.569314454756, 5481.0, 5601.991641504568, 5402.864928376822, 5476.397596264487, 5591.496765291144, 5675.716214189191, 5733.140028995517], "value": 5110.248459841718, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4662546365864, 351.25183359444395, 353.1788591524706, 355.03784743345534, 355.6887723242165, 5582.8604284892845, 5602.271704678391, 5602.991461536922, 5631.662100273981, 5662.745176467059, 5540.2243685884005, 5593.552515798737, 5603.635763675368, 5604.663720176793, 5615.438456154383, 4177.7284476509, 4217.072244106292, 4250.107477429743, 4297.548757380477, 4407.515173330931, 4358.5423530529315, 4358.673099517533, 4377.496587892385, 4402.908618406247, 4435.51209366971, 4317.438732964726, 4322.4164737760475, 4330.84842030529, 4336.436263285768, 4351.4343135392355, 4099.918001639965, 4104.651104656108, 4267.893302667459, 4337.414449049369, 4350.412694286262, 3800.277947190025, 3813.180166264239, 3824.521934758155, 3827.7129215308687, 3830.885073447801, 5417.214503896913, 5422.43064478228, 5451.48210919962, 5472.26124473195, 5481.725913704299, 4819.44576373718, 4926.950730492685, 4953.529414705597, 4964.585093248443, 4965.0, 5711.942880571182, 5719.828405147852, 5740.684262365568, 5756.424357564252, 5781.88436231273, 4246.174145117629, 4256.148770245933, 4257.531671516131, 4270.914581708348, 4281.850135245251, 5669.688167150805, 6001.789937352171, 6010.729517171694, 6018.518518518508, 6024.1566180734735, 4291.206126866539, 4297.269464191095, 4302.9569704303185, 4310.6335961443, 4321.848735294312, 4383.9342409863875, 4399.1641588098155, 4399.890002749957, 4400.361947517592, 4412.161689279027, 4768.593264986837, 4934.827281045146, 4939.53074457926, 4944.258361245842, 4968.428630707443, 5870.530357571384, 5891.410858914074, 5901.468867801918, 5903.35063143054, 5915.201447804491, 1433.2475450388629, 1499.4601943300404, 1503.2634009335347, 1511.5465360391838, 1522.8045983902584, 934.1359242700474, 955.1690029674228, 969.2004096620317, 978.4178413843752, 997.7705127820642, 5439.891202175934, 5602.943970560324, 5606.299212598426, 5611.390703322974, 5622.409646987007, 5879.235699359056, 5887.970560147258, 5915.970420147872, 5930.733117009745, 5954.71973525694, 5503.3120859892515, 5548.973439913548, 5577.8844231153935, 5606.6916319602, 5618.0, 3117.4076925384106, 3117.7349925256153, 3120.9219769505944, 3123.6407813101328, 3126.812391256532, 6034.668093254825, 6096.037575575911, 6137.864495068427, 6148.846278843064, 6167.414095660905, 6031.909521357183, 6047.244094488189, 6064.151018857364, 6065.757369705162, 6067.693107256556, 6101.755929762849, 6111.54163437735, 6113.57204995655, 6121.204243448323, 6173.32093469709, 5673.319201695658, 5909.970450147722, 5937.139114828412, 5973.5519836013345, 6009.158717779602, 5837.67892765885, 5861.35525092248, 5897.262842144732, 5898.407429993963, 5906.704664766658, 5906.616069955439, 6171.537134714825, 6183.474404675473, 6226.159468471879, 6226.221722284715, 4315.4605674290715, 4320.358263859714, 4345.413369195118, 4361.6074553290355, 4369.868903932887, 6042.244719410074, 6049.818505444844, 6072.848178795565, 6080.4831589313635, 6101.572889897665, 5691.3170419549915, 5836.0, 5863.560232982458, 5865.589408741518, 5938.376470470538, 5860.736266868086, 5863.355030946678, 5864.67744274069, 5893.263342082239, 5897.938371093072, 5659.122835960433, 5850.561207909339, 5883.441073098049, 5890.143280508864, 5898.675572843535, 5833.008388574038, 5885.146653735099, 5895.4399332063385, 5899.52220067564, 5902.1736956826935, 5730.398308177581, 5738.938296415176, 5765.798197063083, 5782.421757824184, 5823.330317013598], "value": 4452.354928651228, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.8681867491864, 820.1839170025821, 824.9051359093706, 892.8794612727282, 901.1844280925757, 16446.753298700514, 17980.190891409886, 18181.909090454563, 18183.363582274622, 18186.818131818676, 14611.561653150422, 16241.350345986177, 16369.345226190968, 16424.178791060458, 16425.753613695804, 829.2205326992623, 841.6423020216414, 841.970531031414, 842.2419822160074, 842.9536375499345, 848.4315508609216, 851.6465666748292, 852.4288726553224, 852.6887685994599, 853.0573716043775, 895.3240303570827, 899.8335307968047, 899.9550022498881, 900.1943260781596, 900.9279257659405, 891.269159289383, 892.6652505310509, 892.9241014513742, 893.2496702769672, 893.5085702863431, 659.3274859643137, 660.7125900233414, 660.7951535024158, 660.9008648702685, 661.1834384535096, 14072.577822665336, 14837.777433338508, 14879.70240595182, 14954.0, 15065.322060507304, 14185.716285674229, 14474.0, 14570.0, 14593.78109328361, 14817.0, 12655.683607909783, 12786.0, 12849.67875803103, 12852.0, 13059.542915997894, 4052.898677533056, 4107.404426358161, 4129.236091323085, 4135.442686650028, 4268.317069268902, 83625.16374836235, 85540.0, 86183.0, 86444.56777716134, 86659.56670216672, 10955.945220274007, 11003.724906877393, 11023.17326200538, 11108.111351091893, 11337.716557085974, 22187.778122218893, 22230.777692223193, 22237.22169724052, 22238.444038898833, 22260.777392226195, 8747.518886461306, 9186.40288381253, 9262.397944133745, 9411.858822117674, 9448.858267126, 5172.482751724872, 5263.789448422097, 5336.599755018389, 5423.593230507728, 5430.294061771944, 3689.6494832990825, 3804.372278574004, 3812.3519001769764, 3840.577536470986, 3841.8463261469788, 553.9335279766374, 556.1435389500206, 556.2046273828383, 559.3483591615803, 572.4189947203591, 738.1732459645125, 746.8356961468468, 757.4357103957586, 775.3680750188578, 776.3245976000916, 10400.68371625782, 10454.163666906628, 10684.57261709523, 11108.944455277671, 11126.833097503544, 681.5569879578313, 687.3264201082867, 754.1591125894588, 783.6865253898486, 820.1757233979812, 4993.076280888047, 5057.747112644427, 5060.84817455477, 5072.746362681851, 5198.2722418861385, 73595.63202184062, 73628.0, 73886.0, 105757.47121264345, 130297.0, 63595.36404635987, 88833.0, 90792.54603727072, 91134.0, 105206.0, 54907.0, 56439.0, 62939.6853015732, 79050.60474697589, 93799.53100234592, 10554.618513741503, 10581.576736930436, 10604.681859544226, 10645.89354106449, 10717.0, 51642.0, 51965.7401712989, 52394.0, 52890.206646899554, 53824.46175538195, 73058.63470682825, 74730.25269747233, 79911.60044199742, 79940.60029699815, 80432.19567804247, 14487.20320382389, 14610.853891460949, 14671.266436678337, 14697.55907322782, 14732.705345893237, 15493.14787686688, 15991.680166396374, 16125.038687713008, 16395.180240987665, 16743.413980510624, 14075.718485630436, 14620.488282910048, 14951.850481495481, 15034.774478383051, 15162.772558411853, 13951.58145255644, 14579.271036448346, 14895.329710163285, 15089.113860767404, 15175.924120379328, 14624.268786560842, 14752.188629625474, 14915.32881020335, 14997.925010374878, 15322.080675159526, 11736.706582335168, 11882.82175767347, 12225.69435764113, 12300.50797968107, 12456.43946022413, 12090.939545302217, 12187.878121218675, 12258.632241032783, 12441.129120961446, 12475.0], "value": 7891.092384266591, "unit": "score"}], "name": "dromaeo_css", "value": 7696.226133892215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 115.0, 163.0, 116.0, 122.0, 116.0, 116.0, 155.0, 120.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 159.0, 159.0, 158.0, 159.0, 160.0, 160.0, 160.0], "value": 159.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 115.0, 76.0, 76.0, 74.0, 75.0, 105.0, 74.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 126.0, 82.0, 81.0, 80.0, 100.0, 82.0, 82.0, 111.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 145.0, 146.0, 147.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 49.0, 52.0, 49.0, 51.0, 50.0, 49.0, 57.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 63.0, 58.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 73.0, 74.0, 81.0, 72.0, 73.0, 74.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 122.0, 131.0, 131.0, 122.0, 121.0, 118.0, 117.0, 162.0, 122.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 155.0, 152.0, 158.0, 160.0, 160.0, 149.0, 153.0, 153.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 64.0, 61.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1454.9, "alertThreshold": 2.0}]} 18:36:26 INFO - Return code: 0 18:36:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:36:26 INFO - # TBPL SUCCESS # 18:36:26 INFO - Running post-action listener: _resource_record_post_action 18:36:26 INFO - [mozharness: 2016-11-05 01:36:26.959215Z] Finished run-tests step (success) 18:36:26 INFO - Running post-run listener: _resource_record_post_run 18:36:27 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 359100416; Read time: 52; Write time: 283544 18:36:27 INFO - TinderboxPrint: CPU usage
12.9% 18:36:27 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 52 18:36:27 INFO - TinderboxPrint: I/O write bytes / time
359,100,416 / 283,544 18:36:27 INFO - TinderboxPrint: CPU idle
8,269.0 (87.1%) 18:36:27 INFO - TinderboxPrint: CPU user
1,184.0 (12.5%) 18:36:27 INFO - TinderboxPrint: Swap in / out
0 / 0 18:36:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110862336; Read time: 0; Write time: 85172 18:36:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248238080; Read time: 20; Write time: 198372 18:36:27 INFO - Running post-run listener: _upload_blobber_files 18:36:27 INFO - Blob upload gear active. 18:36:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:27 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 18:36:27 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:36:27 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:36:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:28 INFO - (blobuploader) - INFO - Done attempting. 18:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:31 INFO - (blobuploader) - INFO - Done attempting. 18:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:32 INFO - (blobuploader) - INFO - Done attempting. 18:36:32 INFO - (blobuploader) - INFO - Iteration through files over. 18:36:32 INFO - Return code: 0 18:36:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:36:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:36:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2b27cdf2c0447fd8ecb77303592aeba293543c6687940505e2550101204beb91c8a52deea5d15c3762b2f494af767c2dbae8bc2293874b3cc90da83cf928b5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee358c4f6eca4e8ee906d62986c3efff886cf48afdfff78f72c9bdbf0be510d93a6e9da6b52dfb82e581fa3c44ce42051d1809b2e4df9f463376612510e873b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3a47ea01d95782009b6400c4d974f13464fa7067354b219052bb6eda61782d8e0f61939f9c30382ae8f45b3d7a00df530ecaeaed6bd778c46bbd8c4daa9052"} 18:36:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:36:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:36:32 INFO - Contents: 18:36:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2b27cdf2c0447fd8ecb77303592aeba293543c6687940505e2550101204beb91c8a52deea5d15c3762b2f494af767c2dbae8bc2293874b3cc90da83cf928b5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee358c4f6eca4e8ee906d62986c3efff886cf48afdfff78f72c9bdbf0be510d93a6e9da6b52dfb82e581fa3c44ce42051d1809b2e4df9f463376612510e873b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3a47ea01d95782009b6400c4d974f13464fa7067354b219052bb6eda61782d8e0f61939f9c30382ae8f45b3d7a00df530ecaeaed6bd778c46bbd8c4daa9052"} 18:36:32 INFO - Running post-run listener: copy_logs_to_upload_dir 18:36:32 INFO - Copying logs to upload dir... 18:36:32 INFO - mkdir: /builds/slave/test/build/upload/logs 18:36:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.857103 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-04 18:36:32.209837) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 18:36:32.213587) ========= 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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 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-Aurora/sha512/f2b27cdf2c0447fd8ecb77303592aeba293543c6687940505e2550101204beb91c8a52deea5d15c3762b2f494af767c2dbae8bc2293874b3cc90da83cf928b5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee358c4f6eca4e8ee906d62986c3efff886cf48afdfff78f72c9bdbf0be510d93a6e9da6b52dfb82e581fa3c44ce42051d1809b2e4df9f463376612510e873b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3a47ea01d95782009b6400c4d974f13464fa7067354b219052bb6eda61782d8e0f61939f9c30382ae8f45b3d7a00df530ecaeaed6bd778c46bbd8c4daa9052"} build_url:https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/dBwmA93gSpyqxEllcRwu3g/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2b27cdf2c0447fd8ecb77303592aeba293543c6687940505e2550101204beb91c8a52deea5d15c3762b2f494af767c2dbae8bc2293874b3cc90da83cf928b5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ee358c4f6eca4e8ee906d62986c3efff886cf48afdfff78f72c9bdbf0be510d93a6e9da6b52dfb82e581fa3c44ce42051d1809b2e4df9f463376612510e873b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3a47ea01d95782009b6400c4d974f13464fa7067354b219052bb6eda61782d8e0f61939f9c30382ae8f45b3d7a00df530ecaeaed6bd778c46bbd8c4daa9052"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 18:36:32.239245) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:36:32.239578) ========= 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=920470bf432669ad7e3a5387000001ca-1478308558.152362-1912688117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 18:36:32.322960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 18:36:32.323286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-04 18:36:32.323572) ========= ========= Total master_lag: 0.16 =========