builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1469017198.45 results: success (0) buildid: 20160720023015 builduid: d793c3754662446ca2c5d4cb8c6c7edb revision: e904e18d7dfcd8097f92d44104ca1462fc5d1335 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.447240) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.447704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.448022) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.470188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.470724) ========= 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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 05:19: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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-07-20 05:19:58 (102 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147849 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.638163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.638977) ========= 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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017971 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:58.692047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 05:19:58.692479) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e904e18d7dfcd8097f92d44104ca1462fc5d1335 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e904e18d7dfcd8097f92d44104ca1462fc5d1335 --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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 05:19:58,784 truncating revision to first 12 chars 2016-07-20 05:19:58,784 Setting DEBUG logging. 2016-07-20 05:19:58,784 attempt 1/10 2016-07-20 05:19:58,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e904e18d7dfc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 05:19:59,605 unpacking tar archive at: fx-team-e904e18d7dfc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.175210 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 05:19:59.885913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:59.886692) ========= script_repo_revision: e904e18d7dfcd8097f92d44104ca1462fc5d1335 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:59.887732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:59.888465) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:19:59.901713) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-20 05:19:59.902473) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:20:00 INFO - MultiFileLogger online at 20160720 05:20:00 in /builds/slave/test 05:20:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:20:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:20:00 INFO - {'append_to_log': False, 05:20:00 INFO - 'base_work_dir': '/builds/slave/test', 05:20:00 INFO - 'blob_upload_branch': 'Fx-Team', 05:20:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:20:00 INFO - 'branch': 'Fx-Team', 05:20:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:20:00 INFO - 'config_files': ('talos/linux_config.py',), 05:20:00 INFO - 'default_actions': ('clobber', 05:20:00 INFO - 'read-buildbot-config', 05:20:00 INFO - 'download-and-extract', 05:20:00 INFO - 'populate-webroot', 05:20:00 INFO - 'create-virtualenv', 05:20:00 INFO - 'install', 05:20:00 INFO - 'run-tests'), 05:20:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:20:00 INFO - 'download_minidump_stackwalk': True, 05:20:00 INFO - 'download_symbols': 'ondemand', 05:20:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:20:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:20:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:20:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:20:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:20:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:20:00 INFO - 'installer_path': 'installer.exe', 05:20:00 INFO - 'log_level': 'info', 05:20:00 INFO - 'log_name': 'talos', 05:20:00 INFO - 'log_to_console': True, 05:20:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:20:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:20:00 INFO - 'opt_config_files': (), 05:20:00 INFO - 'pip_index': False, 05:20:00 INFO - 'sps_profile': False, 05:20:00 INFO - 'sps_profile_interval': 0, 05:20:00 INFO - 'suite': 'dromaeojs', 05:20:00 INFO - 'system_bits': '32', 05:20:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:20:00 INFO - 'title': 'talos-linux64-ix-003', 05:20:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:20:00 INFO - 'use_talos_json': True, 05:20:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:20:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:20:00 INFO - 'work_dir': 'build'} 05:20:00 INFO - [mozharness: 2016-07-20 12:20:00.084418Z] Running clobber step. 05:20:00 INFO - Running pre-action listener: _resource_record_pre_action 05:20:00 INFO - Running main action method: clobber 05:20:00 INFO - rmtree: /builds/slave/test/build 05:20:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:20:00 INFO - Running post-action listener: _resource_record_post_action 05:20:00 INFO - [mozharness: 2016-07-20 12:20:00.557176Z] Finished clobber step (success) 05:20:00 INFO - [mozharness: 2016-07-20 12:20:00.557278Z] Running read-buildbot-config step. 05:20:00 INFO - Running pre-action listener: _resource_record_pre_action 05:20:00 INFO - Running main action method: read_buildbot_config 05:20:00 INFO - Using buildbot properties: 05:20:00 INFO - { 05:20:00 INFO - "project": "", 05:20:00 INFO - "product": "firefox", 05:20:00 INFO - "script_repo_revision": "production", 05:20:00 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:20:00 INFO - "repository": "", 05:20:00 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:20:00 INFO - "buildid": "20160720023015", 05:20:00 INFO - "pgo_build": "True", 05:20:00 INFO - "basedir": "/builds/slave/test", 05:20:00 INFO - "buildnumber": 1336, 05:20:00 INFO - "slavename": "talos-linux64-ix-003", 05:20:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:20:00 INFO - "platform": "ubuntu64_hw", 05:20:00 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:20:00 INFO - "branch": "fx-team", 05:20:00 INFO - "repo_path": "integration/fx-team", 05:20:00 INFO - "revision": "e904e18d7dfcd8097f92d44104ca1462fc5d1335", 05:20:00 INFO - "stage_platform": "linux64-pgo", 05:20:00 INFO - "builduid": "d793c3754662446ca2c5d4cb8c6c7edb", 05:20:00 INFO - "slavebuilddir": "test" 05:20:00 INFO - } 05:20:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:20:00 INFO - Running post-action listener: _resource_record_post_action 05:20:00 INFO - [mozharness: 2016-07-20 12:20:00.559716Z] Finished read-buildbot-config step (success) 05:20:00 INFO - [mozharness: 2016-07-20 12:20:00.559806Z] Running download-and-extract step. 05:20:00 INFO - Running pre-action listener: _resource_record_pre_action 05:20:00 INFO - Running main action method: download_and_extract 05:20:00 INFO - mkdir: /builds/slave/test/build/tests 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:00 INFO - https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:20:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:20:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:20:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:20:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:20:05 INFO - Downloaded 1361 bytes. 05:20:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:20:05 INFO - Using the following test package requirements: 05:20:05 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:20:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:20:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:20:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'jsshell-linux-x86_64.zip'], 05:20:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:20:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:20:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:20:05 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:20:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:20:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:20:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:20:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:05 INFO - https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:20:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:20:20 INFO - Downloaded 24343482 bytes. 05:20:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:20:21 INFO - Return code: 0 05:20:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:21 INFO - https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:20:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:20:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:20:23 INFO - Downloaded 24343482 bytes. 05:20:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:20:24 INFO - Return code: 0 05:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:24 INFO - https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:20:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:20:25 INFO - Downloaded 14403317 bytes. 05:20:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:20:25 INFO - Return code: 0 05:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:25 INFO - https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:20:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:20:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:20:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:20:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:20:27 INFO - Downloaded 60896485 bytes. 05:20:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:20:27 INFO - mkdir: /builds/slave/test/properties 05:20:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:27 INFO - Contents: 05:20:27 INFO - build_url:https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:20:27 INFO - Running post-action listener: _resource_record_post_action 05:20:27 INFO - Running post-action listener: set_extra_try_arguments 05:20:27 INFO - [mozharness: 2016-07-20 12:20:27.907929Z] Finished download-and-extract step (success) 05:20:27 INFO - [mozharness: 2016-07-20 12:20:27.908164Z] Running populate-webroot step. 05:20:27 INFO - Running pre-action listener: _resource_record_pre_action 05:20:27 INFO - Running main action method: populate_webroot 05:20:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:27 INFO - '--tppagecycles', 05:20:27 INFO - '7'], 05:20:27 INFO - 'tests': ['tsvgm']}}, 05:20:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['damp', 'tps']}, 05:20:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'tests': ['damp', 'tps']}, 05:20:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['dromaeo_dom']}, 05:20:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['basic_compositor_video']}, 05:20:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['a11yr', 05:20:27 INFO - 'ts_paint', 05:20:27 INFO - 'tpaint', 05:20:27 INFO - 'sessionrestore', 05:20:27 INFO - 'sessionrestore_no_auto_restore', 05:20:27 INFO - 'tabpaint']}, 05:20:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['tsvgx', 05:20:27 INFO - 'tsvgr_opacity', 05:20:27 INFO - 'tart', 05:20:27 INFO - 'tscrollx', 05:20:27 INFO - 'cart']}, 05:20:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:27 INFO - 'tsvgr_opacity', 05:20:27 INFO - 'tart', 05:20:27 INFO - 'tscrollx', 05:20:27 INFO - 'cart']}, 05:20:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'talos_options': ['--disable-e10s'], 05:20:27 INFO - 'tests': ['tp5o']}, 05:20:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'tests': ['tp5o']}, 05:20:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'talos_options': ['--disable-e10s', 05:20:27 INFO - '--xperf_path', 05:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:27 INFO - 'tests': ['tp5n']}, 05:20:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:27 INFO - 'talos_options': ['--xperf_path', 05:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:27 INFO - 'tests': ['tp5n']}}, 05:20:27 INFO - 'talos.zip': {'path': '', 05:20:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:27 INFO - Running post-action listener: _resource_record_post_action 05:20:27 INFO - [mozharness: 2016-07-20 12:20:27.923927Z] Finished populate-webroot step (success) 05:20:27 INFO - [mozharness: 2016-07-20 12:20:27.924017Z] Running create-virtualenv step. 05:20:27 INFO - Running pre-action listener: _resource_record_pre_action 05:20:27 INFO - Running main action method: create_virtualenv 05:20:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:20:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:28 INFO - Using real prefix '/usr' 05:20:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:30 INFO - Installing pip.................done. 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:20:30 INFO - Using env: {'DISPLAY': ':0', 05:20:30 INFO - 'HOME': '/home/cltbld', 05:20:30 INFO - 'LANG': 'en_US.UTF-8', 05:20:30 INFO - 'LANGUAGE': 'en_US:en', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MAIL': '/var/mail/cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SHLVL': '1', 05:20:30 INFO - 'TERM': 'linux', 05:20:30 INFO - 'TMOUT': '86400', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - '_': '/tools/buildbot/bin/python'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:20:30 INFO - Cleaning up... 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:20:30 INFO - Using env: {'DISPLAY': ':0', 05:20:30 INFO - 'HOME': '/home/cltbld', 05:20:30 INFO - 'LANG': 'en_US.UTF-8', 05:20:30 INFO - 'LANGUAGE': 'en_US:en', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MAIL': '/var/mail/cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SHLVL': '1', 05:20:30 INFO - 'TERM': 'linux', 05:20:30 INFO - 'TMOUT': '86400', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - '_': '/tools/buildbot/bin/python'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Downloading/unpacking psutil>=3.1.1 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:32 INFO - Installing collected packages: psutil 05:20:32 INFO - Running setup.py install for psutil 05:20:32 INFO - building 'psutil._psutil_linux' extension 05:20:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:20:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:20:33 INFO - building 'psutil._psutil_posix' extension 05:20:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:20:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:20:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:33 INFO - Successfully installed psutil 05:20:33 INFO - Cleaning up... 05:20:33 INFO - Return code: 0 05:20:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:33 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:20:33 INFO - Using env: {'DISPLAY': ':0', 05:20:33 INFO - 'HOME': '/home/cltbld', 05:20:33 INFO - 'LANG': 'en_US.UTF-8', 05:20:33 INFO - 'LANGUAGE': 'en_US:en', 05:20:33 INFO - 'LOGNAME': 'cltbld', 05:20:33 INFO - 'MAIL': '/var/mail/cltbld', 05:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:33 INFO - 'MOZ_NO_REMOTE': '1', 05:20:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:33 INFO - 'NO_EM_RESTART': '1', 05:20:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:33 INFO - 'PWD': '/builds/slave/test', 05:20:33 INFO - 'SHELL': '/bin/bash', 05:20:33 INFO - 'SHLVL': '1', 05:20:33 INFO - 'TERM': 'linux', 05:20:33 INFO - 'TMOUT': '86400', 05:20:33 INFO - 'USER': 'cltbld', 05:20:33 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:33 INFO - '_': '/tools/buildbot/bin/python'} 05:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:35 INFO - Installing collected packages: mozsystemmonitor 05:20:35 INFO - Running setup.py install for mozsystemmonitor 05:20:35 INFO - Successfully installed mozsystemmonitor 05:20:35 INFO - Cleaning up... 05:20:35 INFO - Return code: 0 05:20:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20: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==2.5.1'] in /builds/slave/test/build 05:20: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==2.5.1 05:20:35 INFO - Using env: {'DISPLAY': ':0', 05:20:35 INFO - 'HOME': '/home/cltbld', 05:20:35 INFO - 'LANG': 'en_US.UTF-8', 05:20:35 INFO - 'LANGUAGE': 'en_US:en', 05:20:35 INFO - 'LOGNAME': 'cltbld', 05:20:35 INFO - 'MAIL': '/var/mail/cltbld', 05:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:35 INFO - 'MOZ_NO_REMOTE': '1', 05:20:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:35 INFO - 'NO_EM_RESTART': '1', 05:20:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:35 INFO - 'PWD': '/builds/slave/test', 05:20:35 INFO - 'SHELL': '/bin/bash', 05:20:35 INFO - 'SHLVL': '1', 05:20:35 INFO - 'TERM': 'linux', 05:20:35 INFO - 'TMOUT': '86400', 05:20:35 INFO - 'USER': 'cltbld', 05:20:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:35 INFO - '_': '/tools/buildbot/bin/python'} 05:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:38 INFO - warning: no files found matching '*.txt' 05:20:38 INFO - no previously-included directories found matching 'build' 05:20:38 INFO - no previously-included directories found matching 'dist' 05:20:38 INFO - no previously-included directories found matching '.git*' 05:20:38 INFO - Installing collected packages: jsonschema, functools32 05:20:38 INFO - Running setup.py install for functools32 05:20:38 INFO - warning: no files found matching '*.txt' 05:20:38 INFO - no previously-included directories found matching 'build' 05:20:38 INFO - no previously-included directories found matching 'dist' 05:20:38 INFO - no previously-included directories found matching '.git*' 05:20:38 INFO - Successfully installed jsonschema functools32 05:20:38 INFO - Cleaning up... 05:20:38 INFO - Return code: 0 05:20:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:38 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:20:38 INFO - Using env: {'DISPLAY': ':0', 05:20:38 INFO - 'HOME': '/home/cltbld', 05:20:38 INFO - 'LANG': 'en_US.UTF-8', 05:20:38 INFO - 'LANGUAGE': 'en_US:en', 05:20:38 INFO - 'LOGNAME': 'cltbld', 05:20:38 INFO - 'MAIL': '/var/mail/cltbld', 05:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:38 INFO - 'MOZ_NO_REMOTE': '1', 05:20:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:38 INFO - 'NO_EM_RESTART': '1', 05:20:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:38 INFO - 'PWD': '/builds/slave/test', 05:20:38 INFO - 'SHELL': '/bin/bash', 05:20:38 INFO - 'SHLVL': '1', 05:20:38 INFO - 'TERM': 'linux', 05:20:38 INFO - 'TMOUT': '86400', 05:20:38 INFO - 'USER': 'cltbld', 05:20:38 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:38 INFO - '_': '/tools/buildbot/bin/python'} 05:20:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:41 INFO - Downloading docopt-0.6.1.tar.gz 05:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:41 INFO - Running setup.py install for blobuploader 05:20:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:41 INFO - Running setup.py install for requests 05:20:41 INFO - Running setup.py install for docopt 05:20:41 INFO - Successfully installed blobuploader requests docopt 05:20:41 INFO - Cleaning up... 05:20:41 INFO - Return code: 0 05:20:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:20:41 INFO - Using env: {'DISPLAY': ':0', 05:20:41 INFO - 'HOME': '/home/cltbld', 05:20:41 INFO - 'LANG': 'en_US.UTF-8', 05:20:41 INFO - 'LANGUAGE': 'en_US:en', 05:20:41 INFO - 'LOGNAME': 'cltbld', 05:20:41 INFO - 'MAIL': '/var/mail/cltbld', 05:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:41 INFO - 'MOZ_NO_REMOTE': '1', 05:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:41 INFO - 'NO_EM_RESTART': '1', 05:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:41 INFO - 'PWD': '/builds/slave/test', 05:20:41 INFO - 'SHELL': '/bin/bash', 05:20:41 INFO - 'SHLVL': '1', 05:20:41 INFO - 'TERM': 'linux', 05:20:41 INFO - 'TMOUT': '86400', 05:20:41 INFO - 'USER': 'cltbld', 05:20:41 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:41 INFO - '_': '/tools/buildbot/bin/python'} 05:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:42 INFO - Running setup.py (path:/tmp/pip-rabf1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:42 INFO - Running setup.py (path:/tmp/pip-6nctjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:42 INFO - Running setup.py (path:/tmp/pip-505l6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:42 INFO - Running setup.py (path:/tmp/pip-jASsxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:42 INFO - Running setup.py (path:/tmp/pip-Cw5isM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:42 INFO - Running setup.py (path:/tmp/pip-3Xi3wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:42 INFO - Running setup.py (path:/tmp/pip-eeZhGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:42 INFO - Running setup.py (path:/tmp/pip-OhDSYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:42 INFO - Running setup.py (path:/tmp/pip-pBxzNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:42 INFO - Running setup.py (path:/tmp/pip-D4mGzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:42 INFO - Running setup.py (path:/tmp/pip-3YVtZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:42 INFO - Running setup.py (path:/tmp/pip-VJCfHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:43 INFO - Running setup.py (path:/tmp/pip-xCTlxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:43 INFO - Running setup.py (path:/tmp/pip-8WtbuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:43 INFO - Running setup.py (path:/tmp/pip-Grwp42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:43 INFO - Running setup.py (path:/tmp/pip-7LHUvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:43 INFO - Running setup.py (path:/tmp/pip-eB6nNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:43 INFO - Running setup.py install for manifestparser 05:20:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:43 INFO - Running setup.py install for mozcrash 05:20:43 INFO - Running setup.py install for mozdebug 05:20:43 INFO - Running setup.py install for mozdevice 05:20:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:43 INFO - Running setup.py install for mozfile 05:20:43 INFO - Running setup.py install for mozhttpd 05:20:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:43 INFO - Running setup.py install for mozinfo 05:20:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozInstall 05:20:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozleak 05:20:44 INFO - Running setup.py install for mozlog 05:20:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for moznetwork 05:20:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozprocess 05:20:44 INFO - Running setup.py install for mozprofile 05:20:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozrunner 05:20:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:44 INFO - Running setup.py install for mozscreenshot 05:20:44 INFO - Running setup.py install for moztest 05:20:45 INFO - Running setup.py install for mozversion 05:20:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:45 INFO - Cleaning up... 05:20:45 INFO - Return code: 0 05:20:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:45 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:20:45 INFO - Using env: {'DISPLAY': ':0', 05:20:45 INFO - 'HOME': '/home/cltbld', 05:20:45 INFO - 'LANG': 'en_US.UTF-8', 05:20:45 INFO - 'LANGUAGE': 'en_US:en', 05:20:45 INFO - 'LOGNAME': 'cltbld', 05:20:45 INFO - 'MAIL': '/var/mail/cltbld', 05:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:45 INFO - 'MOZ_NO_REMOTE': '1', 05:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:45 INFO - 'NO_EM_RESTART': '1', 05:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:45 INFO - 'PWD': '/builds/slave/test', 05:20:45 INFO - 'SHELL': '/bin/bash', 05:20:45 INFO - 'SHLVL': '1', 05:20:45 INFO - 'TERM': 'linux', 05:20:45 INFO - 'TMOUT': '86400', 05:20:45 INFO - 'USER': 'cltbld', 05:20:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:45 INFO - '_': '/tools/buildbot/bin/python'} 05:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:45 INFO - Running setup.py (path:/tmp/pip-PvSE0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:45 INFO - Running setup.py (path:/tmp/pip-y8ZM6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:45 INFO - Running setup.py (path:/tmp/pip-14MumA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:45 INFO - Running setup.py (path:/tmp/pip-dBEMwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:45 INFO - Running setup.py (path:/tmp/pip-Z_3rJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:45 INFO - Running setup.py (path:/tmp/pip-nlxn4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:45 INFO - Running setup.py (path:/tmp/pip-gKP1Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:45 INFO - Running setup.py (path:/tmp/pip-Qt2KSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:45 INFO - Running setup.py (path:/tmp/pip-HSAbuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:46 INFO - Running setup.py (path:/tmp/pip-RTQ7EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:46 INFO - Running setup.py (path:/tmp/pip-CHi5hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:46 INFO - Running setup.py (path:/tmp/pip-TWQhP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:46 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)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:46 INFO - Running setup.py (path:/tmp/pip-bXkwxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:46 INFO - Running setup.py (path:/tmp/pip-yog_Bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:46 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)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:46 INFO - Running setup.py (path:/tmp/pip-GI5Yzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:46 INFO - Running setup.py (path:/tmp/pip-kDf64u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:46 INFO - Running setup.py (path:/tmp/pip-y3IRaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:20:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:48 INFO - Downloading blessings-1.6.tar.gz 05:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:48 INFO - Installing collected packages: blessings 05:20:48 INFO - Running setup.py install for blessings 05:20:48 INFO - Successfully installed blessings 05:20:48 INFO - Cleaning up... 05:20:48 INFO - Return code: 0 05:20:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:48 INFO - Reading from file tmpfile_stdout 05:20:48 INFO - Current package versions: 05:20:48 INFO - argparse == 1.2.1 05:20:48 INFO - blessings == 1.6 05:20:48 INFO - blobuploader == 1.2.4 05:20:48 INFO - docopt == 0.6.1 05:20:48 INFO - functools32 == 3.2.3-2 05:20:48 INFO - jsonschema == 2.5.1 05:20:48 INFO - manifestparser == 1.1 05:20:48 INFO - mozInstall == 1.12 05:20:48 INFO - mozcrash == 0.17 05:20:48 INFO - mozdebug == 0.1 05:20:48 INFO - mozdevice == 0.48 05:20:48 INFO - mozfile == 1.2 05:20:48 INFO - mozhttpd == 0.7 05:20:48 INFO - mozinfo == 0.9 05:20:48 INFO - mozleak == 0.1 05:20:48 INFO - mozlog == 3.3 05:20:48 INFO - moznetwork == 0.27 05:20:48 INFO - mozprocess == 0.23 05:20:48 INFO - mozprofile == 0.28 05:20:48 INFO - mozrunner == 6.12 05:20:48 INFO - mozscreenshot == 0.1 05:20:48 INFO - mozsystemmonitor == 0.3 05:20:48 INFO - moztest == 0.7 05:20:48 INFO - mozversion == 1.4 05:20:48 INFO - psutil == 3.1.1 05:20:48 INFO - requests == 1.2.3 05:20:48 INFO - wsgiref == 0.1.2 05:20:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:20:48 INFO - Using env: {'DISPLAY': ':0', 05:20:48 INFO - 'HOME': '/home/cltbld', 05:20:48 INFO - 'LANG': 'en_US.UTF-8', 05:20:48 INFO - 'LANGUAGE': 'en_US:en', 05:20:48 INFO - 'LOGNAME': 'cltbld', 05:20:48 INFO - 'MAIL': '/var/mail/cltbld', 05:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:48 INFO - 'MOZ_NO_REMOTE': '1', 05:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:48 INFO - 'NO_EM_RESTART': '1', 05:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:48 INFO - 'PWD': '/builds/slave/test', 05:20:48 INFO - 'SHELL': '/bin/bash', 05:20:48 INFO - 'SHLVL': '1', 05:20:48 INFO - 'TERM': 'linux', 05:20:48 INFO - 'TMOUT': '86400', 05:20:48 INFO - 'USER': 'cltbld', 05:20:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:48 INFO - '_': '/tools/buildbot/bin/python'} 05:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:20:49 INFO - Cleaning up... 05:20:49 INFO - Return code: 0 05:20:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, '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': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:20:49 INFO - Using env: {'DISPLAY': ':0', 05:20:49 INFO - 'HOME': '/home/cltbld', 05:20:49 INFO - 'LANG': 'en_US.UTF-8', 05:20:49 INFO - 'LANGUAGE': 'en_US:en', 05:20:49 INFO - 'LOGNAME': 'cltbld', 05:20:49 INFO - 'MAIL': '/var/mail/cltbld', 05:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:49 INFO - 'MOZ_NO_REMOTE': '1', 05:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:49 INFO - 'NO_EM_RESTART': '1', 05:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:49 INFO - 'PWD': '/builds/slave/test', 05:20:49 INFO - 'SHELL': '/bin/bash', 05:20:49 INFO - 'SHLVL': '1', 05:20:49 INFO - 'TERM': 'linux', 05:20:49 INFO - 'TMOUT': '86400', 05:20:49 INFO - 'USER': 'cltbld', 05:20:49 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:49 INFO - '_': '/tools/buildbot/bin/python'} 05:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:49 INFO - Cleaning up... 05:20:49 INFO - Return code: 0 05:20:49 INFO - Running post-action listener: _resource_record_post_action 05:20:49 INFO - Running post-action listener: _start_resource_monitoring 05:20:49 INFO - Starting resource monitoring. 05:20:49 INFO - [mozharness: 2016-07-20 12:20:49.456835Z] Finished create-virtualenv step (success) 05:20:49 INFO - [mozharness: 2016-07-20 12:20:49.457277Z] Running install step. 05:20:49 INFO - Running pre-action listener: _resource_record_pre_action 05:20:49 INFO - Running main action method: install 05:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:49 INFO - Reading from file tmpfile_stdout 05:20:49 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:49 INFO - Reading from file tmpfile_stdout 05:20:49 INFO - Output received: 05:20:49 INFO - Usage: mozinstall [options] installer 05:20:49 INFO - Options: 05:20:49 INFO - -h, --help show this help message and exit 05:20:49 INFO - -d DEST, --destination=DEST 05:20:49 INFO - Directory to install application into. [default: 05:20:49 INFO - "/builds/slave/test"] 05:20:49 INFO - --app=APP Application being installed. [default: firefox] 05:20:49 INFO - mkdir: /builds/slave/test/build/application 05:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:21:07 INFO - Reading from file tmpfile_stdout 05:21:07 INFO - Output received: 05:21:07 INFO - /builds/slave/test/build/application/firefox/firefox 05:21:07 INFO - Running post-action listener: _resource_record_post_action 05:21:07 INFO - [mozharness: 2016-07-20 12:21:07.004881Z] Finished install step (success) 05:21:07 INFO - [mozharness: 2016-07-20 12:21:07.005159Z] Running run-tests step. 05:21:07 INFO - Running pre-action listener: _resource_record_pre_action 05:21:07 INFO - Running main action method: run_tests 05:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:07 INFO - Python 2.7.3 05:21:07 INFO - Return code: 0 05:21:07 INFO - grabbing minidump binary from tooltool 05:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:07 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 0x1def3b0>, '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 0x1df02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:21:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:21:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:21:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:07 INFO - Return code: 0 05:21:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:21:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:21:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:21:07 INFO - Using env: {'DISPLAY': ':0', 05:21:07 INFO - 'HOME': '/home/cltbld', 05:21:07 INFO - 'LANG': 'en_US.UTF-8', 05:21:07 INFO - 'LANGUAGE': 'en_US:en', 05:21:07 INFO - 'LOGNAME': 'cltbld', 05:21:07 INFO - 'MAIL': '/var/mail/cltbld', 05:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:07 INFO - 'MOZ_NO_REMOTE': '1', 05:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:07 INFO - 'NO_EM_RESTART': '1', 05:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:07 INFO - 'PWD': '/builds/slave/test', 05:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:07 INFO - 'SHELL': '/bin/bash', 05:21:07 INFO - 'SHLVL': '1', 05:21:07 INFO - 'TERM': 'linux', 05:21:07 INFO - 'TMOUT': '86400', 05:21:07 INFO - 'USER': 'cltbld', 05:21:07 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469015394.979264-2106357212', 05:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:07 INFO - '_': '/tools/buildbot/bin/python'} 05:21:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:21:07 INFO - mozversion application_buildid: 20160720023015 05:21:07 INFO - mozversion application_changeset: e904e18d7dfcd8097f92d44104ca1462fc5d1335 05:21:07 INFO - mozversion application_display_name: Nightly 05:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:07 INFO - mozversion application_name: Firefox 05:21:07 INFO - mozversion application_remotingname: firefox 05:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:21:07 INFO - mozversion application_vendor: Mozilla 05:21:07 INFO - mozversion application_version: 50.0a1 05:21:07 INFO - mozversion platform_buildid: 20160720023015 05:21:07 INFO - mozversion platform_changeset: e904e18d7dfcd8097f92d44104ca1462fc5d1335 05:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:21:07 INFO - mozversion platform_version: 50.0a1 05:21:07 INFO - using testdate: 1469017267 05:21:07 INFO - actual date: 1469017267 05:21:07 INFO - starting webserver on 'localhost:42537' 05:21:07 INFO - SUITE-START | Running 2 tests 05:21:07 INFO - TEST-START | dromaeo_css 05:21:07 INFO - operating with platform_type : linux_ 05:21:07 INFO - Initialising browser for dromaeo_css test... 05:21:07 INFO - TEST-INFO | started process 24564 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHMJnST/profile http://localhost:42537/getInfo.html) 05:21:11 INFO - PROCESS | 24564 | __metrics Screen width/height:1600/1200 05:21:11 INFO - PROCESS | 24564 | colorDepth:24 05:21:11 INFO - PROCESS | 24564 | Browser inner width/height: 1024/697 05:21:11 INFO - PROCESS | 24564 | __metrics 05:21:12 INFO - PROCESS | 24564 | 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" 05:21:12 INFO - PROCESS | 24564 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:13 INFO - TEST-INFO | 24564: exit 0 05:21:13 INFO - Browser initialized. 05:21:13 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:13 INFO - TEST-INFO | started process 24647 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHMJnST/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:25 INFO - PROCESS | 24647 | RSS: Main: 157319168 05:21:25 INFO - PROCESS | 24647 | 05:22:16 INFO - PROCESS | 24647 | 1469017336662 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:24:24 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42537/tests/dromaeo/cssquery-ext.html) 05:24:25 INFO - PROCESS | 24647 | RSS: Main: 269324288 05:24:25 INFO - PROCESS | 24647 | 05:27:28 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-ext.html (next: http://localhost:42537/tests/dromaeo/cssquery-jquery.html) 05:27:28 INFO - PROCESS | 24647 | RSS: Main: 255926272 05:27:28 INFO - PROCESS | 24647 | 05:30:30 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42537/tests/dromaeo/cssquery-mootools.html) 05:30:31 INFO - PROCESS | 24647 | RSS: Main: 228401152 05:30:31 INFO - PROCESS | 24647 | 05:33:33 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42537/tests/dromaeo/cssquery-prototype.html) 05:33:33 INFO - PROCESS | 24647 | RSS: Main: 185819136 05:33:33 INFO - PROCESS | 24647 | 05:36:36 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42537/tests/dromaeo/cssquery-yui.html) 05:36:37 INFO - PROCESS | 24647 | RSS: Main: 184856576 05:36:37 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | Cycle 1(1): loaded http://localhost:42537/tests/dromaeo/cssquery-yui.html (next: http://localhost:42537/tests/dromaeo/cssquery-dojo.html) 05:39:39 INFO - PROCESS | 24647 | RSS: Main: 278208512 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | __start_tp_report 05:39:39 INFO - PROCESS | 24647 | _x_x_mozilla_page_load 05:39:39 INFO - PROCESS | 24647 | _x_x_mozilla_page_load_details 05:39:39 INFO - PROCESS | 24647 | |i|pagename|runs| 05:39:39 INFO - PROCESS | 24647 | |0;dojo.html;3056.220663730748;3080.7535397168213;3098.7056229658183;3116.9844150779245;3130.2174456385906;5134.512221338967;5272.525472707455;5424.972875135629;5542.6674399536005;5545.778168873246;5351.625386222956;5403.918941215885;5439;5457.126859702448;5476.096444086731;4843.055604157191;4886.1204983103025;4906.656534042619;4916.508349165073;5095.312132862054;4968.180250258703;5025.924611130836;5040.495950404967;5055.823046193384;5056.115179843531;4843.757812109398;4917.40991081069;4973.055119527278;4997.200447928332;5012.147934851084;4843.491433399513;4851.587615052689;4855.32025516428;4856.757162141878;4978.929530150998;4868.099401610713;4884.3161957326;4912.582430493412;4915.3855768029;4966.602671786249;5259.973700131513;5303.390110137345;5315.867103322448;5342.065138600729;5420.457954204588;5757.395473475309;5827.796027139072;5851.824445266649;5855.590108692393;5856.736446859859;5815.040518314473;5889.263842019747;5896.351401345849;5904.5571582131515;5905.173275741399;5234.581233501311;5350.625456218105;5385.973070134624;5482.095454250044;5488.9725551372385;5936.5844390892225;5974.2830860297045;5988.520918326523;5989.101634754735;5994.490468310155;5751.884962300731;5761.107028410519;5761.164631128547;5763.625364351304;5763.971180144073;5549.5837812164245;5727.398623144593;5737.51231145349;5847.590668653153;5864.882702345929;5763.337216220189;5769.798057067983;5782.508486778587;5811.0411782056335;5824.504917082096;5753.050746626844;5768.625039372428;5771.480566748929;5785.30576330843;5802.448767367094;4295.591918767712;4356.891077723082;4391.143726973273;4426.291793313118;4466.39703640011;3925.0972276376024;3950.9802450988136;3962.5641179470235;3992.401139828991;4011.8997025074023;5544.972275138679;5549.250851135126;5550.000999820071;5551.528120109836;5555.388907220203;5631.070873305859;5767.567432442584;5865.3254875689845;5920.052791553331;5967.075103358986;5546.805861794818;5549.1676248563035;5552.3892371839065;5575.721213939287;5583.748731307101;5047.848564543069;5060.671056381323;5066.847994560169;5069;5112.105381558213;5842.795502157489;5988.760449581968;6024.27708674953;6048.425399587032;6069.939300607026;5820.912686309794;5875.265591801025;5876.206712093813;5881.470667639933;5931.288245410578;6066.302375226729;6066.484348830399;6078.270607526948;6080.118382834553;6082.087686847009;5591.6365436246515;5646.604737668488;5647.265855438766;5772.567057470623;5782.2483077199695;5757.165211044291;5787.710614469344;5789.044807606866;5832.24180856486;5850.180974663635;5676.631018983753;5678.574106941914;5678.74445649955;5682.545396368363;5684.602077854511;5421.05131601976;5631.831045068655;5661.66030038199;5664.85837854057;5677.1768093627015;5680.2047713321;5684.175794509691;5684.289463817023;5725.427457254408;5740.368559458373;5724.484796368347;5739.9139012915675;5745.6552606843725;5799.101139323411;5804.100364443518;5380.811671591476;5436.864078398072;5570.693611851387;5597.804076857454;5615.719214039364 05:39:39 INFO - PROCESS | 24647 | |1;ext.html;588.629163626915;611.5688439650047;635.0569404434414;636.984075398115;647.9254885688141;33087.50368744471;45169;45686;45717.77141114298;45755.16979320831;46605.80547750702;46891.765541172244;47011.29483057757;47044.764776176075;47054.76472617632;9047.819043619123;9193;9202.631894724203;9202.76993075175;9204.40171388861;8966.372353935227;9049.592768325441;9061.411008284473;9067.138621830916;9067.546622668873;9063.13900179482;9081.137291957253;9092;9105;9105.817883642323;8963.148500892405;8996;9007.504587247699;9011.954940225258;9084.454932704059;8941.284697224206;9010.59452324647;9011.774705632346;9014.819703605892;9017.278617710568;45504.317435238496;45792.313115303295;45800;45808;45887.770561147314;3507.0706262840113;3612.440071788877;4137.8551750688885;4189.706720529564;4305.784710764449;32911.835440822884;33552.7000957146;34480.310393792235;34568.135796605035;34616;4302.290122129845;4337.457817772778;4342.652587792969;4345.217860785057;4363.061941682522;353939;354172;355077;355311;356933;64399.67800161063;64504.67747661232;64727.352726472134;64744;64768.676156618916;64713.058608240884;64844.67577662082;65613.34386656072;65655.34344656588;65675.67162164158;17327.91336043337;17392.47822565325;17506.824931750518;17565.560860978578;17568.912155439397;13709.725805484033;13737.656558586115;13774.242416667179;13783.51757688476;13786.103903246258;19367.83792972426;19640.803591963897;19642.60714785725;19794.70307945382;19845.60308793845;6823.93176068233;6828.2488926218075;6872.347127022925;7118.5017048806085;7239.384652304508;952.6570434643522;979.8334283171878;1044.2898828796492;1091.514276147107;1129.949152288149;33578;33874.32251354959;33886.83056584701;33888.1527961798;33962.49056264158;1014.7209517382779;1127.0645364347572;1150.2408410449063;1161.4018780328138;1184.9170558061026;17209.1395430228;17631.471055868344;17685.911570442324;17788.46634600964;17799.73300400495;546587;546754;547229;548801.6078552869;554551;61478;61927.38072619216;61970.070448943305;62066.37933620696;62078.379216208166;1249.9187552809037;1309.9738005239842;1367.1045465220298;1395.5115709501592;1401.7617005109155;30648.233794155323;35148.82425587856;35354.46968295529;35388.07783465038;35530.46704299489;51988.480115199876;52439.73780131075;52452.47547524476;52494.21258681199;52497.737511313724;384042;384624;385626;385844;386519;28751.137465876058;29222;29312.853435732686;29334;29367.265818354714;48780.51219487902;49517;49712.751436242586;50504.98990020253;50854.491455086456;42234.36648450337;42809;42982.78508607437;43264.35103473513;44501.554984449744;41493;41634;42622.57377426186;43070.78464607657;44183;41984.85870624342;42153.57846421497;43468.56531434772;43491.56508434875;43510;35148.12129696778;37885.09741653952;38524.80737596294;38703.41944870885;38904.416433754086;28912.855435722686;29176.7082329174;29260.56109158407;29376.265593359483;29688.851555742945 05:39:39 INFO - PROCESS | 24647 | |2;jquery.html;375.9530058742657;392.4427313215236;402.762370201581;403.30631314139686;415.4855551514729;14518.201498917555;18340;20043;20214;20364.796352036476;19790.703139452882;19818.306359277427;19822.306219282327;19977.10103045363;20057.59884802303;5604.047311956977;5724.34170070442;5756.107803290497;5917.349091599921;5919.88160236795;5748.626339287954;5750.884982300351;5773.769049238025;5783.132530120473;5786.132080187985;5563.36021357543;5758.452946970031;5765.279340082489;5780.53755699543;5809.941900581003;5799.855003624901;5813.912791308134;5836.095405212178;5845.85385365365;5849.210356601847;5058.0895438821;5159.277701121846;5171.152286974209;5184.455632158644;5187.870303242411;23207.53584928309;23414.53170936589;23503.647445288334;23594.174203902952;23683.763162368326;2378.108209421467;2479.0827393864206;2638.828476149044;2652.4827658606578;2966.2732630505448;14305.016044587068;14383;14478.42086316546;14731.705365892729;15215.923920380437;1750.6148647297575;1817.282173541457;1819.5178277756406;1868.6356160548698;1877.0614692653674;61940.380596193914;74617;82141;88180.55909720475;91766.54116729373;9982.950085249528;10196.38821670702;10487.123487578312;11293;11551.711207219887;15884.285207165705;15903;15934.840651593335;15942.202889855693;15949.122798245975;8186.386021048445;8445.873311900326;8485;8579.313654907592;8613.095624959415;8121.634526446324;8179.754607361789;8227.629756660965;8414.621342039623;8419.536925469036;4776.95223047772;4834.564889159992;4885.828995985124;4964.776585053609;4997.875053123628;1884.0862181841796;1893.2237782509105;1987.9701804472945;2027.5742094160096;2036.4705176654177;870.664794054281;922.8246633139682;963.426761077158;988.604558176735;1038.5897570459776;19916.60166796656;19950.800491995185;20414.489637759176;20491.69262461064;20537.691934620994;957.698325027614;970.8834939807268;976.6484065736329;982.4694664880884;1025.3437799808128;10503.789924201474;11047.147487522136;11106.389148596743;11183.105351571854;11287.830682539769;463014;471695;487508;500660;504135;91052;91467;93885.5305723467;94377.52811236038;99867.50066249623;955.861400096982;974.6296407365297;998.9500524973722;1000.559753708366;1008.3395376028712;17491.737623935907;17708.359365238037;18261.269549218414;18326;18474.63050738951;44314.89795714269;44502;44812;45205.77397112994;45489.31766023446;61634.691826540584;61992.69003654953;62181.37818621756;62333.68833155987;63292.68353658386;7400.222976587596;8389.496630202208;8470.9834819822;8580.399372044147;8913;11264;13195.20828750278;13214.27321497327;13257.138286011379;13772.380242888894;11249.1563132767;11553.7111572208;11692.298462092302;11880.287182769061;12185.451654675384;11659.533618655498;11949.581764638544;12148;12381.442835072263;12429.12996090265;11474.77050459003;11802.76394472123;11913.523459061887;12055.21641093326;12235.388230588613;7538.057742782153;7576.3938884887975;7691.88462173079;7776.650050747843;7921.128675845767;10921.563137474412;11094.389808560607;11306.660800176009;11406.771864562828;11459.62484501865 05:39:39 INFO - PROCESS | 24647 | |3;mootools.html;1334.37951352621;1526.1911187070857;1556.7820505129282;1726.542466246445;1818.9818101818987;8608.397412181142;8839.425437346576;8920.152585504367;8942.865857012142;9147.451152930813;8752.29981601472;8895.066018068106;9087.90912090879;9092.54537273137;9097.727068187949;2321.3964369264027;2363.09021026905;2363.2673871100014;2364.645303204516;2366.065404165354;2364.3498038039593;2406.38637147528;2408.349745568695;2410.4937963027787;2418.996116611604;2352.3178278299274;2384.0940442631777;2391.270662447953;2421.4793819328843;2424.5878200705924;2343.0744855781873;2353.446939969106;2376.9762302376926;2378.3459548624096;2388.3432056184515;2304.066852924568;2316.328264803205;2320.8491448055825;2321.558903808271;2321.6981792366882;8850.336224783103;9001.604751263565;9044.502552359618;9056.909430905673;9062.365634405594;5414.40441551429;5542.722863856791;5546.55627549795;5549.19536667181;5550.084236101038;3497.7551571390013;3513.139280876176;3547.379208638478;3560.839762210707;3626.0390996385977;1161.814109742437;1164.1210885781265;1175.964721058361;1180.5926955200441;1207.1188032736152;12954.676133096653;13172.275524846129;13201.537946171838;13217.537386191438;13230.206187628677;4773.64197685175;4838;4877.878053048702;4954.405471343462;5005.424376196711;11133.610323638633;11139;11139.944300278446;11140.220184587;11144.665660030212;5462.7268636568015;5466.398696143421;5473.370562385377;5477.123660214426;5480.314960629921;4496.235639941217;4699.676536451853;5018.673786203885;5024.62315326352;5080.466551012164;4147.792610369469;4159.708820382604;4172.91654166921;4188.027874221294;4218.093109981369;1298.7662220800346;1299;1299.9610011699665;1313.750387426386;1320.5510126557017;924.1867156902018;991.7322322972757;1031.3657100883054;1031.6698656429871;1040.812653722322;6136.263648362135;6318.431341179224;6354.618722876642;6383.585066970725;6398.5521013528605;466.99066018680116;471.8275086410268;472.87468820762524;480.45468393373073;486.6569068806483;4345.478542574911;4398.164348773723;4430.4683437987005;4442.666799990014;4460.085682435093;11098.334099954029;11606.24559403636;11630.418479076012;11645.941770291094;11650.126240531825;11026.944865275622;11475.598354057567;11492.712682183012;11599.130065244972;11816.82274765862;8506.064332923374;8639.438436501732;8666.78333041679;8668.826623467621;8678.305735541142;6238.719257633326;6333.809985700436;6360.204974378203;6397.648129352744;6542.54202205841;10374.377537347784;10555.472226388496;10611.575536978744;10731.40977246259;10839.458027098772;9737.80524389494;10026.899731002597;11055.889441105486;11176.55293788272;11197.104231661611;8200.58997050157;8412.663493460193;8682.826343473222;8806.8991376078;8833.381663283508;9125.863112053457;9206.677766278415;9229.49237791928;9248.676296329597;9251.398659087137;7934.404919631166;7982.600869956363;8092.6762929482165;8124.350051995708;8294.958525207578;7849.921500784919;7895.64469598858;7968.84062318762;7997.160298168841;8165.183481651788;7308.5614863108385;7410.4442166836625;7421.962890185515;7424.888626670712;7426.2945020223;5445.945540544544;5450;5538.6122971391615;5585;5646.463585959327;5409.702466364388;5424.294841670558;5424.566034717134;5456.890862182813;5512.641678290898 05:39:39 INFO - PROCESS | 24647 | |4;prototype.html;323.89797213877625;325.9722923551498;332.4730302470584;335.11863798210703;337.39269315232605;5296.417394086652;5315.627906046579;5319.361676598814;5337.332833395825;5339.519443250117;5228.73856307185;5233.555147812431;5281.735913204334;5281.947180528194;5303.840884773454;3656.7074634029213;3940.783256920868;4052.0477687743355;4075.001624601962;4077.7145599807877;4046;4141.440905477767;4146.523149837762;4155.833766649331;4160.854370097048;4098.774567398792;4100.671946244308;4103.712740108193;4130.772807495587;4143.212789569987;3939.1333906540517;4055.8174882130374;4157.792110394484;4165.125323682046;4181.498220213593;3412.812295323756;3540.4689296605716;3666.3767159582935;3692.9446058309145;3696.7227457940494;5117.897642047138;5126.897462050738;5155.922661160085;5169.353830771153;5173.189383715707;4632.2125238709505;4632.687105279898;4734.668573199878;4744.881377965578;4757.1437141314545;5357.249985002103;5423;5425.860569280456;5426.701531415771;5441.347038355383;3996.140829721624;4004.279229738646;4009.4386785850006;4034.616711412411;4050.2304562133395;5505.724713764296;5669.489745922887;5718.342390625131;5728.856778580527;5752.612665743712;4008.7594744315434;4009.578994205625;4011.498562679665;4020.9396859047138;4032.8991775205855;4092.6930480214105;4092.8362865485046;4098.549159592442;4098.815553300049;4099.754014759124;4513.796879140446;4606.792694328763;4669.813207471663;4698.201305778026;4700.788464519036;5455.481729235717;5479.479449452296;5505.146702261156;5509.669419834822;5525.309336332958;1391.9234442105578;1392.5543825975792;1398.9228294213601;1409.689868228988;1412.3220853989937;854.9145085491401;909.3952521573079;913.3880300198882;921.5668635741191;922.5018490015317;4669.529098334082;4726.409198850144;4738.573528382462;4743.766620678648;4745.627186406797;4969.975150124226;4981;4995.825146119869;4999.700077979752;5010.89978200434;4734.644901632391;4791.856244312677;4791.880202994883;4796.424429068465;4929.704217746947;2960.2747326905046;3008.736330741094;3019.9547006794915;3021.3202029543177;3022.969770302269;5830.212921255661;5855.736491857877;5889.911651325234;5920.763169473259;5925.614835035709;5636.746346414421;5724.255846739898;5797.651950959868;5803.245578074908;5869.61847479921;5512.6968016758665;5670.5747068969995;5740;5833.0667093266575;5834.994977586631;5667.259608658266;5668.37647858727;5689.829305120853;5695.629784064023;5696.287964004499;5426.321709786277;5543.8891222176135;5558.638688485235;5617.876424715155;5638.126090455986;5721.599488035964;5736.368999410145;5753.108268218432;5754.798582049608;5771.499410153307;3968.7420317679257;3981.1639555693628;3990.6607938324405;4001.8399264029113;4034.23349563582;5571.359293681278;5577.609567330248;5579.2746942897165;5618.578606604439;5700.686462244616;5493.725313734218;5501.724913754216;5532.30846144232;5541.750621222135;5543.667379957054;5504.642168277268;5550.833474995757;5566.332040155207;5573.972130139324;5575.888482230252;5252.921206181987;5536.889262214652;5550.195221692913;5557.194206839905;5559.861003474945;5573.303337082864;5613.438656134354;5631.239782629293;5640.492355688008;5680.2047713321;5260.289860868734;5482.2873026506295;5550;5561.053631229038;5563.718875261124 05:39:39 INFO - PROCESS | 24647 | |5;yui.html;653.8398092467345;700.7477308169061;710.662435343212;747.0325927923342;786.4652036615099;15152.469663561778;15601.063936163839;15607.765883511742;15640.452584159557;15729.921350393246;12993.675158121052;14168.433262669481;14255.857441425582;14282.571522854305;14292.285385730724;799.7880561651167;819.713100414853;820.216693058128;824.9422540422142;825.8348330333927;661.560062558398;786.8780339047457;814.5764202614637;819.9139090395512;820.8481430935266;864.9481031138151;865.640759084979;869.5261082709939;869.9304055675531;870.9608067636972;862.7929296968745;864.567716141929;865.074370423647;865.6796985115516;865.8614621660568;502.2190493782167;611.2634275697774;625.7121724006962;627.1282916745727;628.5976974736172;14457.204853733041;14478.348474318576;14486.927565362106;14492.855071449361;14501;13178.736425271536;13396.397162127727;13770;13831.170129792245;13908.51320203788;10842.512086956105;11071.335719856834;11397.487113079931;13045.412956416983;13474;4515.5710207530565;4541.931871021938;4589.2886602576655;4699.530046995274;4703.976480117612;80976;83423.16576834215;84322;85629.14370856274;85897.57051214766;10780.78438431227;10878.238523303451;10886.510107045202;10905.34567925927;11046.889531104747;19231.615367692568;19351.419457416017;19380.903095484715;19433.611327773364;19474.318398855972;7877.054753429625;8104.918950810535;8378.204070613281;8379.748607541784;8432.957835210786;4272.273713468718;4635.258358662598;4650.162970665313;4802.303665968415;4840.588549973291;3284.293876816496;3351.748618853596;3354.1614596350914;3354.3123659649714;3355.496675498646;628.3747671067317;630.9779157729457;640.0846789091551;655.8524332025352;658.1444122640644;504.8258350868944;553.335996803837;609.4910749524108;659.399946049093;743.8698227810112;8836.248918841842;8850.911490885137;9062.546872656341;9343.813123737487;9363.080568483445;668.298286798869;671.1878626861552;698.1900994845954;701.7052837808133;741.8850078237882;4436.844710435119;4439.311906654474;4456.64346852251;4550.84072057483;4588.174128656807;106265;115098.4245078786;115196;116913;118785;60771.392286077455;61806;63110.684446578394;85388.14611853927;98003;86142.13857861467;86145.13854861497;86670;90670;92250;7921.920780792118;8380.413371063967;8420;8504.23461888433;8628.654853806029;39370.803145985235;46004.539954600026;48873.75563122162;49698.50301497083;51370.74314628403;49591.50408495869;50734.49265507446;60629;69967.65016174887;73013;13123.475060997835;13229.603111906657;13321.134126282148;13409.66475838112;13490.460381584626;13612.659683507596;13788;13831.585052448443;13943.790843137158;14281.214533200773;12701.93649031749;13186.274754888573;13341.933290333487;13424.93287533556;14273.714525709634;12641.93679031599;12677.556285529961;13089.214647120822;13225.140365876186;13688.452461901412;12622.368881556069;12712.809307860574;13049.608511744664;13157.934210328887;13454.529091482143;9197.77005574866;9393.248540116638;10027.197824174194;10378.905340539446;10633.361998280128;10095.798084038131;11148.219624626197;11395.715107122389;11473.36896470702;11504.94247528757 05:39:39 INFO - PROCESS | 24647 | __end_tp_report 05:39:39 INFO - PROCESS | 24647 | __start_cc_report 05:39:39 INFO - PROCESS | 24647 | _x_x_mozilla_cycle_collect,990 05:39:39 INFO - PROCESS | 24647 | __end_cc_report 05:39:39 INFO - PROCESS | 24647 | __startTimestamp1469018379959__endTimestamp 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | ------- Summary: start ------- 05:39:39 INFO - PROCESS | 24647 | Number of tests: 6 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#0] dojo.html Cycles:165 Average:5398.86 Median:5615.72 stddev:626.32 (11.2%) stddev-sans-first:600.67 05:39:39 INFO - PROCESS | 24647 | Values: 3056.2 3080.8 3098.7 3117.0 3130.2 5134.5 5272.5 5425.0 5542.7 5545.8 5351.6 5403.9 5439.0 5457.1 5476.1 4843.1 4886.1 4906.7 4916.5 5095.3 4968.2 5025.9 5040.5 5055.8 5056.1 4843.8 4917.4 4973.1 4997.2 5012.1 4843.5 4851.6 4855.3 4856.8 4978.9 4868.1 4884.3 4912.6 4915.4 4966.6 5260.0 5303.4 5315.9 5342.1 5420.5 5757.4 5827.8 5851.8 5855.6 5856.7 5815.0 5889.3 5896.4 5904.6 5905.2 5234.6 5350.6 5386.0 5482.1 5489.0 5936.6 5974.3 5988.5 5989.1 5994.5 5751.9 5761.1 5761.2 5763.6 5764.0 5549.6 5727.4 5737.5 5847.6 5864.9 5763.3 5769.8 5782.5 5811.0 5824.5 5753.1 5768.6 5771.5 5785.3 5802.4 4295.6 4356.9 4391.1 4426.3 4466.4 3925.1 3951.0 3962.6 3992.4 4011.9 5545.0 5549.3 5550.0 5551.5 5555.4 5631.1 5767.6 5865.3 5920.1 5967.1 5546.8 5549.2 5552.4 5575.7 5583.7 5047.8 5060.7 5066.8 5069.0 5112.1 5842.8 5988.8 6024.3 6048.4 6069.9 5820.9 5875.3 5876.2 5881.5 5931.3 6066.3 6066.5 6078.3 6080.1 6082.1 5591.6 5646.6 5647.3 5772.6 5782.2 5757.2 5787.7 5789.0 5832.2 5850.2 5676.6 5678.6 5678.7 5682.5 5684.6 5421.1 5631.8 5661.7 5664.9 5677.2 5680.2 5684.2 5684.3 5725.4 5740.4 5724.5 5739.9 5745.7 5799.1 5804.1 5380.8 5436.9 5570.7 5597.8 5615.7 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#1] ext.html Cycles:180 Average:60542.95 Median:4340.06 stddev:115950.42 (2671.6%) stddev-sans-first:116188.31 05:39:39 INFO - PROCESS | 24647 | Values: 588.6 611.6 635.1 637.0 647.9 33087.5 45169.0 45686.0 45717.8 45755.2 46605.8 46891.8 47011.3 47044.8 47054.8 9047.8 9193.0 9202.6 9202.8 9204.4 8966.4 9049.6 9061.4 9067.1 9067.5 9063.1 9081.1 9092.0 9105.0 9105.8 8963.1 8996.0 9007.5 9012.0 9084.5 8941.3 9010.6 9011.8 9014.8 9017.3 45504.3 45792.3 45800.0 45808.0 45887.8 3507.1 3612.4 4137.9 4189.7 4305.8 32911.8 33552.7 34480.3 34568.1 34616.0 4302.3 4337.5 4342.7 4345.2 4363.1 353939.0 354172.0 355077.0 355311.0 356933.0 64399.7 64504.7 64727.4 64744.0 64768.7 64713.1 64844.7 65613.3 65655.3 65675.7 17327.9 17392.5 17506.8 17565.6 17568.9 13709.7 13737.7 13774.2 13783.5 13786.1 19367.8 19640.8 19642.6 19794.7 19845.6 6823.9 6828.2 6872.3 7118.5 7239.4 952.7 979.8 1044.3 1091.5 1129.9 33578.0 33874.3 33886.8 33888.2 33962.5 1014.7 1127.1 1150.2 1161.4 1184.9 17209.1 17631.5 17685.9 17788.5 17799.7 546587.0 546754.0 547229.0 548801.6 554551.0 61478.0 61927.4 61970.1 62066.4 62078.4 1249.9 1310.0 1367.1 1395.5 1401.8 30648.2 35148.8 35354.5 35388.1 35530.5 51988.5 52439.7 52452.5 52494.2 52497.7 384042.0 384624.0 385626.0 385844.0 386519.0 28751.1 29222.0 29312.9 29334.0 29367.3 48780.5 49517.0 49712.8 50505.0 50854.5 42234.4 42809.0 42982.8 43264.4 44501.6 41493.0 41634.0 42622.6 43070.8 44183.0 41984.9 42153.6 43468.6 43491.6 43510.0 35148.1 37885.1 38524.8 38703.4 38904.4 28912.9 29176.7 29260.6 29376.3 29688.9 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#2] jquery.html Cycles:180 Average:29296.11 Median:403.03 stddev:80177.82 (19893.5%) stddev-sans-first:80373.34 05:39:39 INFO - PROCESS | 24647 | Values: 376.0 392.4 402.8 403.3 415.5 14518.2 18340.0 20043.0 20214.0 20364.8 19790.7 19818.3 19822.3 19977.1 20057.6 5604.0 5724.3 5756.1 5917.3 5919.9 5748.6 5750.9 5773.8 5783.1 5786.1 5563.4 5758.5 5765.3 5780.5 5809.9 5799.9 5813.9 5836.1 5845.9 5849.2 5058.1 5159.3 5171.2 5184.5 5187.9 23207.5 23414.5 23503.6 23594.2 23683.8 2378.1 2479.1 2638.8 2652.5 2966.3 14305.0 14383.0 14478.4 14731.7 15215.9 1750.6 1817.3 1819.5 1868.6 1877.1 61940.4 74617.0 82141.0 88180.6 91766.5 9983.0 10196.4 10487.1 11293.0 11551.7 15884.3 15903.0 15934.8 15942.2 15949.1 8186.4 8445.9 8485.0 8579.3 8613.1 8121.6 8179.8 8227.6 8414.6 8419.5 4777.0 4834.6 4885.8 4964.8 4997.9 1884.1 1893.2 1988.0 2027.6 2036.5 870.7 922.8 963.4 988.6 1038.6 19916.6 19950.8 20414.5 20491.7 20537.7 957.7 970.9 976.6 982.5 1025.3 10503.8 11047.1 11106.4 11183.1 11287.8 463014.0 471695.0 487508.0 500660.0 504135.0 91052.0 91467.0 93885.5 94377.5 99867.5 955.9 974.6 999.0 1000.6 1008.3 17491.7 17708.4 18261.3 18326.0 18474.6 44314.9 44502.0 44812.0 45205.8 45489.3 61634.7 61992.7 62181.4 62333.7 63292.7 7400.2 8389.5 8471.0 8580.4 8913.0 11264.0 13195.2 13214.3 13257.1 13772.4 11249.2 11553.7 11692.3 11880.3 12185.5 11659.5 11949.6 12148.0 12381.4 12429.1 11474.8 11802.8 11913.5 12055.2 12235.4 7538.1 7576.4 7691.9 7776.7 7921.1 10921.6 11094.4 11306.7 11406.8 11459.6 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#3] mootools.html Cycles:180 Average:6091.36 Median:472.35 stddev:3521.10 (745.4%) stddev-sans-first:3512.83 05:39:39 INFO - PROCESS | 24647 | Values: 1334.4 1526.2 1556.8 1726.5 1819.0 8608.4 8839.4 8920.2 8942.9 9147.5 8752.3 8895.1 9087.9 9092.5 9097.7 2321.4 2363.1 2363.3 2364.6 2366.1 2364.3 2406.4 2408.3 2410.5 2419.0 2352.3 2384.1 2391.3 2421.5 2424.6 2343.1 2353.4 2377.0 2378.3 2388.3 2304.1 2316.3 2320.8 2321.6 2321.7 8850.3 9001.6 9044.5 9056.9 9062.4 5414.4 5542.7 5546.6 5549.2 5550.1 3497.8 3513.1 3547.4 3560.8 3626.0 1161.8 1164.1 1176.0 1180.6 1207.1 12954.7 13172.3 13201.5 13217.5 13230.2 4773.6 4838.0 4877.9 4954.4 5005.4 11133.6 11139.0 11139.9 11140.2 11144.7 5462.7 5466.4 5473.4 5477.1 5480.3 4496.2 4699.7 5018.7 5024.6 5080.5 4147.8 4159.7 4172.9 4188.0 4218.1 1298.8 1299.0 1300.0 1313.8 1320.6 924.2 991.7 1031.4 1031.7 1040.8 6136.3 6318.4 6354.6 6383.6 6398.6 467.0 471.8 472.9 480.5 486.7 4345.5 4398.2 4430.5 4442.7 4460.1 11098.3 11606.2 11630.4 11645.9 11650.1 11026.9 11475.6 11492.7 11599.1 11816.8 8506.1 8639.4 8666.8 8668.8 8678.3 6238.7 6333.8 6360.2 6397.6 6542.5 10374.4 10555.5 10611.6 10731.4 10839.5 9737.8 10026.9 11055.9 11176.6 11197.1 8200.6 8412.7 8682.8 8806.9 8833.4 9125.9 9206.7 9229.5 9248.7 9251.4 7934.4 7982.6 8092.7 8124.4 8295.0 7849.9 7895.6 7968.8 7997.2 8165.2 7308.6 7410.4 7422.0 7424.9 7426.3 5445.9 5450.0 5538.6 5585.0 5646.5 5409.7 5424.3 5424.6 5456.9 5512.6 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#4] prototype.html Cycles:180 Average:4597.47 Median:5231.15 stddev:1351.46 (25.8%) stddev-sans-first:1316.63 05:39:39 INFO - PROCESS | 24647 | Values: 323.9 326.0 332.5 335.1 337.4 5296.4 5315.6 5319.4 5337.3 5339.5 5228.7 5233.6 5281.7 5281.9 5303.8 3656.7 3940.8 4052.0 4075.0 4077.7 4046.0 4141.4 4146.5 4155.8 4160.9 4098.8 4100.7 4103.7 4130.8 4143.2 3939.1 4055.8 4157.8 4165.1 4181.5 3412.8 3540.5 3666.4 3692.9 3696.7 5117.9 5126.9 5155.9 5169.4 5173.2 4632.2 4632.7 4734.7 4744.9 4757.1 5357.2 5423.0 5425.9 5426.7 5441.3 3996.1 4004.3 4009.4 4034.6 4050.2 5505.7 5669.5 5718.3 5728.9 5752.6 4008.8 4009.6 4011.5 4020.9 4032.9 4092.7 4092.8 4098.5 4098.8 4099.8 4513.8 4606.8 4669.8 4698.2 4700.8 5455.5 5479.5 5505.1 5509.7 5525.3 1391.9 1392.6 1398.9 1409.7 1412.3 854.9 909.4 913.4 921.6 922.5 4669.5 4726.4 4738.6 4743.8 4745.6 4970.0 4981.0 4995.8 4999.7 5010.9 4734.6 4791.9 4791.9 4796.4 4929.7 2960.3 3008.7 3020.0 3021.3 3023.0 5830.2 5855.7 5889.9 5920.8 5925.6 5636.7 5724.3 5797.7 5803.2 5869.6 5512.7 5670.6 5740.0 5833.1 5835.0 5667.3 5668.4 5689.8 5695.6 5696.3 5426.3 5543.9 5558.6 5617.9 5638.1 5721.6 5736.4 5753.1 5754.8 5771.5 3968.7 3981.2 3990.7 4001.8 4034.2 5571.4 5577.6 5579.3 5618.6 5700.7 5493.7 5501.7 5532.3 5541.8 5543.7 5504.6 5550.8 5566.3 5574.0 5575.9 5252.9 5536.9 5550.2 5557.2 5559.9 5573.3 5613.4 5631.2 5640.5 5680.2 5260.3 5482.3 5550.0 5561.1 5563.7 05:39:39 INFO - PROCESS | 24647 | 05:39:39 INFO - PROCESS | 24647 | [#5] yui.html Cycles:180 Average:19597.76 Median:4674.85 stddev:28342.39 (606.3%) stddev-sans-first:28386.21 05:39:39 INFO - PROCESS | 24647 | Values: 653.8 700.7 710.7 747.0 786.5 15152.5 15601.1 15607.8 15640.5 15729.9 12993.7 14168.4 14255.9 14282.6 14292.3 799.8 819.7 820.2 824.9 825.8 661.6 786.9 814.6 819.9 820.8 864.9 865.6 869.5 869.9 871.0 862.8 864.6 865.1 865.7 865.9 502.2 611.3 625.7 627.1 628.6 14457.2 14478.3 14486.9 14492.9 14501.0 13178.7 13396.4 13770.0 13831.2 13908.5 10842.5 11071.3 11397.5 13045.4 13474.0 4515.6 4541.9 4589.3 4699.5 4704.0 80976.0 83423.2 84322.0 85629.1 85897.6 10780.8 10878.2 10886.5 10905.3 11046.9 19231.6 19351.4 19380.9 19433.6 19474.3 7877.1 8104.9 8378.2 8379.7 8433.0 4272.3 4635.3 4650.2 4802.3 4840.6 3284.3 3351.7 3354.2 3354.3 3355.5 628.4 631.0 640.1 655.9 658.1 504.8 553.3 609.5 659.4 743.9 8836.2 8850.9 9062.5 9343.8 9363.1 668.3 671.2 698.2 701.7 741.9 4436.8 4439.3 4456.6 4550.8 4588.2 106265.0 115098.4 115196.0 116913.0 118785.0 60771.4 61806.0 63110.7 85388.1 98003.0 86142.1 86145.1 86670.0 90670.0 92250.0 7921.9 8380.4 8420.0 8504.2 8628.7 39370.8 46004.5 48873.8 49698.5 51370.7 49591.5 50734.5 60629.0 69967.7 73013.0 13123.5 13229.6 13321.1 13409.7 13490.5 13612.7 13788.0 13831.6 13943.8 14281.2 12701.9 13186.3 13341.9 13424.9 14273.7 12641.9 12677.6 13089.2 13225.1 13688.5 12622.4 12712.8 13049.6 13157.9 13454.5 9197.8 9393.2 10027.2 10378.9 10633.4 10095.8 11148.2 11395.7 11473.4 11504.9 05:39:39 INFO - PROCESS | 24647 | -------- Summary: end -------- 05:39:39 INFO - PROCESS | 24647 | 05:39:40 INFO - TEST-INFO | 24647: exit 0 05:39:40 INFO - TEST-OK | dromaeo_css | took 1113412ms 05:39:40 INFO - TEST-START | kraken 05:39:40 INFO - operating with platform_type : linux_ 05:39:40 INFO - Initialising browser for kraken test... 05:39:40 INFO - TEST-INFO | started process 24840 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCuHs6l/profile http://localhost:42537/getInfo.html) 05:39:44 INFO - PROCESS | 24840 | __metrics Screen width/height:1600/1200 05:39:44 INFO - PROCESS | 24840 | colorDepth:24 05:39:44 INFO - PROCESS | 24840 | Browser inner width/height: 1024/697 05:39:44 INFO - PROCESS | 24840 | __metrics 05:39:45 INFO - PROCESS | 24840 | 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" 05:39:45 INFO - PROCESS | 24840 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:39:46 INFO - TEST-INFO | 24840: exit 0 05:39:46 INFO - Browser initialized. 05:39:46 INFO - Running cycle 1/1 for kraken test... 05:39:46 INFO - TEST-INFO | started process 24923 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCuHs6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:58 INFO - PROCESS | 24923 | RSS: Main: 157020160 05:39:58 INFO - PROCESS | 24923 | 05:40:00 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42537/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:01 INFO - PROCESS | 24923 | RSS: Main: 213983232 05:40:01 INFO - PROCESS | 24923 | 05:40:04 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42537/tests/kraken/driver.html?testName=audio-dft) 05:40:04 INFO - PROCESS | 24923 | RSS: Main: 192983040 05:40:04 INFO - PROCESS | 24923 | 05:40:08 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42537/tests/kraken/driver.html?testName=audio-fft) 05:40:08 INFO - PROCESS | 24923 | RSS: Main: 269512704 05:40:08 INFO - PROCESS | 24923 | 05:40:10 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42537/tests/kraken/driver.html?testName=audio-oscillator) 05:40:11 INFO - PROCESS | 24923 | RSS: Main: 180494336 05:40:11 INFO - PROCESS | 24923 | 05:40:13 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42537/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:14 INFO - PROCESS | 24923 | RSS: Main: 184762368 05:40:14 INFO - PROCESS | 24923 | 05:40:17 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42537/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:17 INFO - PROCESS | 24923 | RSS: Main: 219705344 05:40:17 INFO - PROCESS | 24923 | 05:40:21 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42537/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:21 INFO - PROCESS | 24923 | RSS: Main: 220065792 05:40:21 INFO - PROCESS | 24923 | 05:40:24 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42537/tests/kraken/driver.html?testName=json-parse-financial) 05:40:25 INFO - PROCESS | 24923 | RSS: Main: 220471296 05:40:25 INFO - PROCESS | 24923 | 05:40:26 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42537/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:27 INFO - PROCESS | 24923 | RSS: Main: 184856576 05:40:27 INFO - PROCESS | 24923 | 05:40:29 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:29 INFO - PROCESS | 24923 | RSS: Main: 190562304 05:40:29 INFO - PROCESS | 24923 | 05:40:31 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:32 INFO - PROCESS | 24923 | RSS: Main: 194732032 05:40:32 INFO - PROCESS | 24923 | 05:40:34 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:35 INFO - PROCESS | 24923 | RSS: Main: 189218816 05:40:35 INFO - PROCESS | 24923 | 05:40:38 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:38 INFO - PROCESS | 24923 | RSS: Main: 192983040 05:40:38 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | Cycle 1(1): loaded http://localhost:42537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42537/tests/kraken/driver.html?testName=ai-astar) 05:40:40 INFO - PROCESS | 24923 | RSS: Main: 244019200 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | __start_tp_report 05:40:40 INFO - PROCESS | 24923 | _x_x_mozilla_page_load 05:40:40 INFO - PROCESS | 24923 | _x_x_mozilla_page_load_details 05:40:40 INFO - PROCESS | 24923 | |i|pagename|runs| 05:40:40 INFO - PROCESS | 24923 | |0;ai-astar;100;110;111;110;110;110;110;111;110;112 05:40:40 INFO - PROCESS | 24923 | |1;audio-beat-detection;142;120;118;177;118;121;145;116;158;122 05:40:40 INFO - PROCESS | 24923 | |2;audio-dft;160;162;160;159;159;160;160;159;161;162 05:40:40 INFO - PROCESS | 24923 | |3;audio-fft;74;74;74;112;74;76;73;74;99;75 05:40:40 INFO - PROCESS | 24923 | |4;audio-oscillator;83;83;117;84;84;83;128;83;84;120 05:40:40 INFO - PROCESS | 24923 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 05:40:40 INFO - PROCESS | 24923 | |6;imaging-darkroom;146;146;145;145;145;146;145;146;147;145 05:40:40 INFO - PROCESS | 24923 | |7;imaging-desaturate;95;95;94;95;95;94;94;95;95;94 05:40:40 INFO - PROCESS | 24923 | |8;json-parse-financial;53;55;53;53;52;53;53;55;52;55 05:40:40 INFO - PROCESS | 24923 | |9;json-stringify-tinderbox;61;62;63;63;63;62;62;62;61;61 05:40:40 INFO - PROCESS | 24923 | |10;stanford-crypto-aes;76;76;76;75;75;75;74;81;81;80 05:40:40 INFO - PROCESS | 24923 | |11;stanford-crypto-ccm;131;118;120;127;118;125;118;120;193;134 05:40:40 INFO - PROCESS | 24923 | |12;stanford-crypto-pbkdf2;162;165;162;158;156;156;158;158;158;154 05:40:40 INFO - PROCESS | 24923 | |13;stanford-crypto-sha256-iterative;64;63;65;62;65;63;64;63;63;62 05:40:40 INFO - PROCESS | 24923 | __end_tp_report 05:40:40 INFO - PROCESS | 24923 | __start_cc_report 05:40:40 INFO - PROCESS | 24923 | _x_x_mozilla_cycle_collect,1691 05:40:40 INFO - PROCESS | 24923 | __end_cc_report 05:40:40 INFO - PROCESS | 24923 | __startTimestamp1469018440668__endTimestamp 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | ------- Summary: start ------- 05:40:40 INFO - PROCESS | 24923 | Number of tests: 14 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:3.37 (3.1%) stddev-sans-first:0.73 05:40:40 INFO - PROCESS | 24923 | Values: 100.0 110.0 111.0 110.0 110.0 110.0 110.0 111.0 110.0 112.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:132.00 stddev:20.96 (15.9%) stddev-sans-first:22.02 05:40:40 INFO - PROCESS | 24923 | Values: 142.0 120.0 118.0 177.0 118.0 121.0 145.0 116.0 158.0 122.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 05:40:40 INFO - PROCESS | 24923 | Values: 160.0 162.0 160.0 159.0 159.0 160.0 160.0 159.0 161.0 162.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#3] audio-fft Cycles:10 Average:80.50 Median:74.00 stddev:13.55 (18.3%) stddev-sans-first:14.17 05:40:40 INFO - PROCESS | 24923 | Values: 74.0 74.0 74.0 112.0 74.0 76.0 73.0 74.0 99.0 75.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.00 stddev:18.67 (22.5%) stddev-sans-first:19.30 05:40:40 INFO - PROCESS | 24923 | Values: 83.0 83.0 117.0 84.0 84.0 83.0 128.0 83.0 84.0 120.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:40:40 INFO - PROCESS | 24923 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 05:40:40 INFO - PROCESS | 24923 | Values: 146.0 146.0 145.0 145.0 145.0 146.0 145.0 146.0 147.0 145.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:40:40 INFO - PROCESS | 24923 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:1.17 (2.2%) stddev-sans-first:1.24 05:40:40 INFO - PROCESS | 24923 | Values: 53.0 55.0 53.0 53.0 52.0 53.0 53.0 55.0 52.0 55.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 05:40:40 INFO - PROCESS | 24923 | Values: 61.0 62.0 63.0 63.0 63.0 62.0 62.0 62.0 61.0 61.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:2.69 (3.5%) stddev-sans-first:2.83 05:40:40 INFO - PROCESS | 24923 | Values: 76.0 76.0 76.0 75.0 75.0 75.0 74.0 81.0 81.0 80.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:126.00 stddev:22.73 (18.0%) stddev-sans-first:24.11 05:40:40 INFO - PROCESS | 24923 | Values: 131.0 118.0 120.0 127.0 118.0 125.0 118.0 120.0 193.0 134.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.70 Median:158.00 stddev:3.33 (2.1%) stddev-sans-first:3.32 05:40:40 INFO - PROCESS | 24923 | Values: 162.0 165.0 162.0 158.0 156.0 156.0 158.0 158.0 158.0 154.0 05:40:40 INFO - PROCESS | 24923 | 05:40:40 INFO - PROCESS | 24923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.07 (1.7%) stddev-sans-first:1.12 05:40:40 INFO - PROCESS | 24923 | Values: 64.0 63.0 65.0 62.0 65.0 63.0 64.0 63.0 63.0 62.0 05:40:40 INFO - PROCESS | 24923 | -------- Summary: end -------- 05:40:40 INFO - PROCESS | 24923 | 05:40:41 INFO - TEST-INFO | 24923: exit 0 05:40:41 INFO - TEST-OK | kraken | took 60994ms 05:40:41 INFO - SUITE-END | took 1174s 05:40:41 INFO - Completed test suite (00:19:34) 05:40:41 INFO - javascript benchmark 05:40:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.220663730748, 3080.7535397168213, 3098.7056229658183, 3116.9844150779245, 3130.2174456385906, 5134.512221338967, 5272.525472707455, 5424.972875135629, 5542.6674399536005, 5545.778168873246, 5351.625386222956, 5403.918941215885, 5439.0, 5457.126859702448, 5476.096444086731, 4843.055604157191, 4886.1204983103025, 4906.656534042619, 4916.508349165073, 5095.312132862054, 4968.180250258703, 5025.924611130836, 5040.495950404967, 5055.823046193384, 5056.115179843531, 4843.757812109398, 4917.40991081069, 4973.055119527278, 4997.200447928332, 5012.147934851084, 4843.491433399513, 4851.587615052689, 4855.32025516428, 4856.757162141878, 4978.929530150998, 4868.099401610713, 4884.3161957326, 4912.582430493412, 4915.3855768029, 4966.602671786249, 5259.973700131513, 5303.390110137345, 5315.867103322448, 5342.065138600729, 5420.457954204588, 5757.395473475309, 5827.796027139072, 5851.824445266649, 5855.590108692393, 5856.736446859859, 5815.040518314473, 5889.263842019747, 5896.351401345849, 5904.5571582131515, 5905.173275741399, 5234.581233501311, 5350.625456218105, 5385.973070134624, 5482.095454250044, 5488.9725551372385, 5936.5844390892225, 5974.2830860297045, 5988.520918326523, 5989.101634754735, 5994.490468310155, 5751.884962300731, 5761.107028410519, 5761.164631128547, 5763.625364351304, 5763.971180144073, 5549.5837812164245, 5727.398623144593, 5737.51231145349, 5847.590668653153, 5864.882702345929, 5763.337216220189, 5769.798057067983, 5782.508486778587, 5811.0411782056335, 5824.504917082096, 5753.050746626844, 5768.625039372428, 5771.480566748929, 5785.30576330843, 5802.448767367094, 4295.591918767712, 4356.891077723082, 4391.143726973273, 4426.291793313118, 4466.39703640011, 3925.0972276376024, 3950.9802450988136, 3962.5641179470235, 3992.401139828991, 4011.8997025074023, 5544.972275138679, 5549.250851135126, 5550.000999820071, 5551.528120109836, 5555.388907220203, 5631.070873305859, 5767.567432442584, 5865.3254875689845, 5920.052791553331, 5967.075103358986, 5546.805861794818, 5549.1676248563035, 5552.3892371839065, 5575.721213939287, 5583.748731307101, 5047.848564543069, 5060.671056381323, 5066.847994560169, 5069.0, 5112.105381558213, 5842.795502157489, 5988.760449581968, 6024.27708674953, 6048.425399587032, 6069.939300607026, 5820.912686309794, 5875.265591801025, 5876.206712093813, 5881.470667639933, 5931.288245410578, 6066.302375226729, 6066.484348830399, 6078.270607526948, 6080.118382834553, 6082.087686847009, 5591.6365436246515, 5646.604737668488, 5647.265855438766, 5772.567057470623, 5782.2483077199695, 5757.165211044291, 5787.710614469344, 5789.044807606866, 5832.24180856486, 5850.180974663635, 5676.631018983753, 5678.574106941914, 5678.74445649955, 5682.545396368363, 5684.602077854511, 5421.05131601976, 5631.831045068655, 5661.66030038199, 5664.85837854057, 5677.1768093627015, 5680.2047713321, 5684.175794509691, 5684.289463817023, 5725.427457254408, 5740.368559458373, 5724.484796368347, 5739.9139012915675, 5745.6552606843725, 5799.101139323411, 5804.100364443518, 5380.811671591476, 5436.864078398072, 5570.693611851387, 5597.804076857454, 5615.719214039364], "value": 5355.5853606599485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.629163626915, 611.5688439650047, 635.0569404434414, 636.984075398115, 647.9254885688141, 33087.50368744471, 45169.0, 45686.0, 45717.77141114298, 45755.16979320831, 46605.80547750702, 46891.765541172244, 47011.29483057757, 47044.764776176075, 47054.76472617632, 9047.819043619123, 9193.0, 9202.631894724203, 9202.76993075175, 9204.40171388861, 8966.372353935227, 9049.592768325441, 9061.411008284473, 9067.138621830916, 9067.546622668873, 9063.13900179482, 9081.137291957253, 9092.0, 9105.0, 9105.817883642323, 8963.148500892405, 8996.0, 9007.504587247699, 9011.954940225258, 9084.454932704059, 8941.284697224206, 9010.59452324647, 9011.774705632346, 9014.819703605892, 9017.278617710568, 45504.317435238496, 45792.313115303295, 45800.0, 45808.0, 45887.770561147314, 3507.0706262840113, 3612.440071788877, 4137.8551750688885, 4189.706720529564, 4305.784710764449, 32911.835440822884, 33552.7000957146, 34480.310393792235, 34568.135796605035, 34616.0, 4302.290122129845, 4337.457817772778, 4342.652587792969, 4345.217860785057, 4363.061941682522, 353939.0, 354172.0, 355077.0, 355311.0, 356933.0, 64399.67800161063, 64504.67747661232, 64727.352726472134, 64744.0, 64768.676156618916, 64713.058608240884, 64844.67577662082, 65613.34386656072, 65655.34344656588, 65675.67162164158, 17327.91336043337, 17392.47822565325, 17506.824931750518, 17565.560860978578, 17568.912155439397, 13709.725805484033, 13737.656558586115, 13774.242416667179, 13783.51757688476, 13786.103903246258, 19367.83792972426, 19640.803591963897, 19642.60714785725, 19794.70307945382, 19845.60308793845, 6823.93176068233, 6828.2488926218075, 6872.347127022925, 7118.5017048806085, 7239.384652304508, 952.6570434643522, 979.8334283171878, 1044.2898828796492, 1091.514276147107, 1129.949152288149, 33578.0, 33874.32251354959, 33886.83056584701, 33888.1527961798, 33962.49056264158, 1014.7209517382779, 1127.0645364347572, 1150.2408410449063, 1161.4018780328138, 1184.9170558061026, 17209.1395430228, 17631.471055868344, 17685.911570442324, 17788.46634600964, 17799.73300400495, 546587.0, 546754.0, 547229.0, 548801.6078552869, 554551.0, 61478.0, 61927.38072619216, 61970.070448943305, 62066.37933620696, 62078.379216208166, 1249.9187552809037, 1309.9738005239842, 1367.1045465220298, 1395.5115709501592, 1401.7617005109155, 30648.233794155323, 35148.82425587856, 35354.46968295529, 35388.07783465038, 35530.46704299489, 51988.480115199876, 52439.73780131075, 52452.47547524476, 52494.21258681199, 52497.737511313724, 384042.0, 384624.0, 385626.0, 385844.0, 386519.0, 28751.137465876058, 29222.0, 29312.853435732686, 29334.0, 29367.265818354714, 48780.51219487902, 49517.0, 49712.751436242586, 50504.98990020253, 50854.491455086456, 42234.36648450337, 42809.0, 42982.78508607437, 43264.35103473513, 44501.554984449744, 41493.0, 41634.0, 42622.57377426186, 43070.78464607657, 44183.0, 41984.85870624342, 42153.57846421497, 43468.56531434772, 43491.56508434875, 43510.0, 35148.12129696778, 37885.09741653952, 38524.80737596294, 38703.41944870885, 38904.416433754086, 28912.855435722686, 29176.7082329174, 29260.56109158407, 29376.265593359483, 29688.851555742945], "value": 20812.82740324158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.9530058742657, 392.4427313215236, 402.762370201581, 403.30631314139686, 415.4855551514729, 14518.201498917555, 18340.0, 20043.0, 20214.0, 20364.796352036476, 19790.703139452882, 19818.306359277427, 19822.306219282327, 19977.10103045363, 20057.59884802303, 5604.047311956977, 5724.34170070442, 5756.107803290497, 5917.349091599921, 5919.88160236795, 5748.626339287954, 5750.884982300351, 5773.769049238025, 5783.132530120473, 5786.132080187985, 5563.36021357543, 5758.452946970031, 5765.279340082489, 5780.53755699543, 5809.941900581003, 5799.855003624901, 5813.912791308134, 5836.095405212178, 5845.85385365365, 5849.210356601847, 5058.0895438821, 5159.277701121846, 5171.152286974209, 5184.455632158644, 5187.870303242411, 23207.53584928309, 23414.53170936589, 23503.647445288334, 23594.174203902952, 23683.763162368326, 2378.108209421467, 2479.0827393864206, 2638.828476149044, 2652.4827658606578, 2966.2732630505448, 14305.016044587068, 14383.0, 14478.42086316546, 14731.705365892729, 15215.923920380437, 1750.6148647297575, 1817.282173541457, 1819.5178277756406, 1868.6356160548698, 1877.0614692653674, 61940.380596193914, 74617.0, 82141.0, 88180.55909720475, 91766.54116729373, 9982.950085249528, 10196.38821670702, 10487.123487578312, 11293.0, 11551.711207219887, 15884.285207165705, 15903.0, 15934.840651593335, 15942.202889855693, 15949.122798245975, 8186.386021048445, 8445.873311900326, 8485.0, 8579.313654907592, 8613.095624959415, 8121.634526446324, 8179.754607361789, 8227.629756660965, 8414.621342039623, 8419.536925469036, 4776.95223047772, 4834.564889159992, 4885.828995985124, 4964.776585053609, 4997.875053123628, 1884.0862181841796, 1893.2237782509105, 1987.9701804472945, 2027.5742094160096, 2036.4705176654177, 870.664794054281, 922.8246633139682, 963.426761077158, 988.604558176735, 1038.5897570459776, 19916.60166796656, 19950.800491995185, 20414.489637759176, 20491.69262461064, 20537.691934620994, 957.698325027614, 970.8834939807268, 976.6484065736329, 982.4694664880884, 1025.3437799808128, 10503.789924201474, 11047.147487522136, 11106.389148596743, 11183.105351571854, 11287.830682539769, 463014.0, 471695.0, 487508.0, 500660.0, 504135.0, 91052.0, 91467.0, 93885.5305723467, 94377.52811236038, 99867.50066249623, 955.861400096982, 974.6296407365297, 998.9500524973722, 1000.559753708366, 1008.3395376028712, 17491.737623935907, 17708.359365238037, 18261.269549218414, 18326.0, 18474.63050738951, 44314.89795714269, 44502.0, 44812.0, 45205.77397112994, 45489.31766023446, 61634.691826540584, 61992.69003654953, 62181.37818621756, 62333.68833155987, 63292.68353658386, 7400.222976587596, 8389.496630202208, 8470.9834819822, 8580.399372044147, 8913.0, 11264.0, 13195.20828750278, 13214.27321497327, 13257.138286011379, 13772.380242888894, 11249.1563132767, 11553.7111572208, 11692.298462092302, 11880.287182769061, 12185.451654675384, 11659.533618655498, 11949.581764638544, 12148.0, 12381.442835072263, 12429.12996090265, 11474.77050459003, 11802.76394472123, 11913.523459061887, 12055.21641093326, 12235.388230588613, 7538.057742782153, 7576.3938884887975, 7691.88462173079, 7776.650050747843, 7921.128675845767, 10921.563137474412, 11094.389808560607, 11306.660800176009, 11406.771864562828, 11459.62484501865], "value": 9372.96447718038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.37951352621, 1526.1911187070857, 1556.7820505129282, 1726.542466246445, 1818.9818101818987, 8608.397412181142, 8839.425437346576, 8920.152585504367, 8942.865857012142, 9147.451152930813, 8752.29981601472, 8895.066018068106, 9087.90912090879, 9092.54537273137, 9097.727068187949, 2321.3964369264027, 2363.09021026905, 2363.2673871100014, 2364.645303204516, 2366.065404165354, 2364.3498038039593, 2406.38637147528, 2408.349745568695, 2410.4937963027787, 2418.996116611604, 2352.3178278299274, 2384.0940442631777, 2391.270662447953, 2421.4793819328843, 2424.5878200705924, 2343.0744855781873, 2353.446939969106, 2376.9762302376926, 2378.3459548624096, 2388.3432056184515, 2304.066852924568, 2316.328264803205, 2320.8491448055825, 2321.558903808271, 2321.6981792366882, 8850.336224783103, 9001.604751263565, 9044.502552359618, 9056.909430905673, 9062.365634405594, 5414.40441551429, 5542.722863856791, 5546.55627549795, 5549.19536667181, 5550.084236101038, 3497.7551571390013, 3513.139280876176, 3547.379208638478, 3560.839762210707, 3626.0390996385977, 1161.814109742437, 1164.1210885781265, 1175.964721058361, 1180.5926955200441, 1207.1188032736152, 12954.676133096653, 13172.275524846129, 13201.537946171838, 13217.537386191438, 13230.206187628677, 4773.64197685175, 4838.0, 4877.878053048702, 4954.405471343462, 5005.424376196711, 11133.610323638633, 11139.0, 11139.944300278446, 11140.220184587, 11144.665660030212, 5462.7268636568015, 5466.398696143421, 5473.370562385377, 5477.123660214426, 5480.314960629921, 4496.235639941217, 4699.676536451853, 5018.673786203885, 5024.62315326352, 5080.466551012164, 4147.792610369469, 4159.708820382604, 4172.91654166921, 4188.027874221294, 4218.093109981369, 1298.7662220800346, 1299.0, 1299.9610011699665, 1313.750387426386, 1320.5510126557017, 924.1867156902018, 991.7322322972757, 1031.3657100883054, 1031.6698656429871, 1040.812653722322, 6136.263648362135, 6318.431341179224, 6354.618722876642, 6383.585066970725, 6398.5521013528605, 466.99066018680116, 471.8275086410268, 472.87468820762524, 480.45468393373073, 486.6569068806483, 4345.478542574911, 4398.164348773723, 4430.4683437987005, 4442.666799990014, 4460.085682435093, 11098.334099954029, 11606.24559403636, 11630.418479076012, 11645.941770291094, 11650.126240531825, 11026.944865275622, 11475.598354057567, 11492.712682183012, 11599.130065244972, 11816.82274765862, 8506.064332923374, 8639.438436501732, 8666.78333041679, 8668.826623467621, 8678.305735541142, 6238.719257633326, 6333.809985700436, 6360.204974378203, 6397.648129352744, 6542.54202205841, 10374.377537347784, 10555.472226388496, 10611.575536978744, 10731.40977246259, 10839.458027098772, 9737.80524389494, 10026.899731002597, 11055.889441105486, 11176.55293788272, 11197.104231661611, 8200.58997050157, 8412.663493460193, 8682.826343473222, 8806.8991376078, 8833.381663283508, 9125.863112053457, 9206.677766278415, 9229.49237791928, 9248.676296329597, 9251.398659087137, 7934.404919631166, 7982.600869956363, 8092.6762929482165, 8124.350051995708, 8294.958525207578, 7849.921500784919, 7895.64469598858, 7968.84062318762, 7997.160298168841, 8165.183481651788, 7308.5614863108385, 7410.4442166836625, 7421.962890185515, 7424.888626670712, 7426.2945020223, 5445.945540544544, 5450.0, 5538.6122971391615, 5585.0, 5646.463585959327, 5409.702466364388, 5424.294841670558, 5424.566034717134, 5456.890862182813, 5512.641678290898], "value": 4764.401791181925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.89797213877625, 325.9722923551498, 332.4730302470584, 335.11863798210703, 337.39269315232605, 5296.417394086652, 5315.627906046579, 5319.361676598814, 5337.332833395825, 5339.519443250117, 5228.73856307185, 5233.555147812431, 5281.735913204334, 5281.947180528194, 5303.840884773454, 3656.7074634029213, 3940.783256920868, 4052.0477687743355, 4075.001624601962, 4077.7145599807877, 4046.0, 4141.440905477767, 4146.523149837762, 4155.833766649331, 4160.854370097048, 4098.774567398792, 4100.671946244308, 4103.712740108193, 4130.772807495587, 4143.212789569987, 3939.1333906540517, 4055.8174882130374, 4157.792110394484, 4165.125323682046, 4181.498220213593, 3412.812295323756, 3540.4689296605716, 3666.3767159582935, 3692.9446058309145, 3696.7227457940494, 5117.897642047138, 5126.897462050738, 5155.922661160085, 5169.353830771153, 5173.189383715707, 4632.2125238709505, 4632.687105279898, 4734.668573199878, 4744.881377965578, 4757.1437141314545, 5357.249985002103, 5423.0, 5425.860569280456, 5426.701531415771, 5441.347038355383, 3996.140829721624, 4004.279229738646, 4009.4386785850006, 4034.616711412411, 4050.2304562133395, 5505.724713764296, 5669.489745922887, 5718.342390625131, 5728.856778580527, 5752.612665743712, 4008.7594744315434, 4009.578994205625, 4011.498562679665, 4020.9396859047138, 4032.8991775205855, 4092.6930480214105, 4092.8362865485046, 4098.549159592442, 4098.815553300049, 4099.754014759124, 4513.796879140446, 4606.792694328763, 4669.813207471663, 4698.201305778026, 4700.788464519036, 5455.481729235717, 5479.479449452296, 5505.146702261156, 5509.669419834822, 5525.309336332958, 1391.9234442105578, 1392.5543825975792, 1398.9228294213601, 1409.689868228988, 1412.3220853989937, 854.9145085491401, 909.3952521573079, 913.3880300198882, 921.5668635741191, 922.5018490015317, 4669.529098334082, 4726.409198850144, 4738.573528382462, 4743.766620678648, 4745.627186406797, 4969.975150124226, 4981.0, 4995.825146119869, 4999.700077979752, 5010.89978200434, 4734.644901632391, 4791.856244312677, 4791.880202994883, 4796.424429068465, 4929.704217746947, 2960.2747326905046, 3008.736330741094, 3019.9547006794915, 3021.3202029543177, 3022.969770302269, 5830.212921255661, 5855.736491857877, 5889.911651325234, 5920.763169473259, 5925.614835035709, 5636.746346414421, 5724.255846739898, 5797.651950959868, 5803.245578074908, 5869.61847479921, 5512.6968016758665, 5670.5747068969995, 5740.0, 5833.0667093266575, 5834.994977586631, 5667.259608658266, 5668.37647858727, 5689.829305120853, 5695.629784064023, 5696.287964004499, 5426.321709786277, 5543.8891222176135, 5558.638688485235, 5617.876424715155, 5638.126090455986, 5721.599488035964, 5736.368999410145, 5753.108268218432, 5754.798582049608, 5771.499410153307, 3968.7420317679257, 3981.1639555693628, 3990.6607938324405, 4001.8399264029113, 4034.23349563582, 5571.359293681278, 5577.609567330248, 5579.2746942897165, 5618.578606604439, 5700.686462244616, 5493.725313734218, 5501.724913754216, 5532.30846144232, 5541.750621222135, 5543.667379957054, 5504.642168277268, 5550.833474995757, 5566.332040155207, 5573.972130139324, 5575.888482230252, 5252.921206181987, 5536.889262214652, 5550.195221692913, 5557.194206839905, 5559.861003474945, 5573.303337082864, 5613.438656134354, 5631.239782629293, 5640.492355688008, 5680.2047713321, 5260.289860868734, 5482.2873026506295, 5550.0, 5561.053631229038, 5563.718875261124], "value": 4166.774128447331, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.8398092467345, 700.7477308169061, 710.662435343212, 747.0325927923342, 786.4652036615099, 15152.469663561778, 15601.063936163839, 15607.765883511742, 15640.452584159557, 15729.921350393246, 12993.675158121052, 14168.433262669481, 14255.857441425582, 14282.571522854305, 14292.285385730724, 799.7880561651167, 819.713100414853, 820.216693058128, 824.9422540422142, 825.8348330333927, 661.560062558398, 786.8780339047457, 814.5764202614637, 819.9139090395512, 820.8481430935266, 864.9481031138151, 865.640759084979, 869.5261082709939, 869.9304055675531, 870.9608067636972, 862.7929296968745, 864.567716141929, 865.074370423647, 865.6796985115516, 865.8614621660568, 502.2190493782167, 611.2634275697774, 625.7121724006962, 627.1282916745727, 628.5976974736172, 14457.204853733041, 14478.348474318576, 14486.927565362106, 14492.855071449361, 14501.0, 13178.736425271536, 13396.397162127727, 13770.0, 13831.170129792245, 13908.51320203788, 10842.512086956105, 11071.335719856834, 11397.487113079931, 13045.412956416983, 13474.0, 4515.5710207530565, 4541.931871021938, 4589.2886602576655, 4699.530046995274, 4703.976480117612, 80976.0, 83423.16576834215, 84322.0, 85629.14370856274, 85897.57051214766, 10780.78438431227, 10878.238523303451, 10886.510107045202, 10905.34567925927, 11046.889531104747, 19231.615367692568, 19351.419457416017, 19380.903095484715, 19433.611327773364, 19474.318398855972, 7877.054753429625, 8104.918950810535, 8378.204070613281, 8379.748607541784, 8432.957835210786, 4272.273713468718, 4635.258358662598, 4650.162970665313, 4802.303665968415, 4840.588549973291, 3284.293876816496, 3351.748618853596, 3354.1614596350914, 3354.3123659649714, 3355.496675498646, 628.3747671067317, 630.9779157729457, 640.0846789091551, 655.8524332025352, 658.1444122640644, 504.8258350868944, 553.335996803837, 609.4910749524108, 659.399946049093, 743.8698227810112, 8836.248918841842, 8850.911490885137, 9062.546872656341, 9343.813123737487, 9363.080568483445, 668.298286798869, 671.1878626861552, 698.1900994845954, 701.7052837808133, 741.8850078237882, 4436.844710435119, 4439.311906654474, 4456.64346852251, 4550.84072057483, 4588.174128656807, 106265.0, 115098.4245078786, 115196.0, 116913.0, 118785.0, 60771.392286077455, 61806.0, 63110.684446578394, 85388.14611853927, 98003.0, 86142.13857861467, 86145.13854861497, 86670.0, 90670.0, 92250.0, 7921.920780792118, 8380.413371063967, 8420.0, 8504.23461888433, 8628.654853806029, 39370.803145985235, 46004.539954600026, 48873.75563122162, 49698.50301497083, 51370.74314628403, 49591.50408495869, 50734.49265507446, 60629.0, 69967.65016174887, 73013.0, 13123.475060997835, 13229.603111906657, 13321.134126282148, 13409.66475838112, 13490.460381584626, 13612.659683507596, 13788.0, 13831.585052448443, 13943.790843137158, 14281.214533200773, 12701.93649031749, 13186.274754888573, 13341.933290333487, 13424.93287533556, 14273.714525709634, 12641.93679031599, 12677.556285529961, 13089.214647120822, 13225.140365876186, 13688.452461901412, 12622.368881556069, 12712.809307860574, 13049.608511744664, 13157.934210328887, 13454.529091482143, 9197.77005574866, 9393.248540116638, 10027.197824174194, 10378.905340539446, 10633.361998280128, 10095.798084038131, 11148.219624626197, 11395.715107122389, 11473.36896470702, 11504.94247528757], "value": 7271.612813809295, "unit": "score"}], "name": "dromaeo_css", "value": 7295.975832761785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 112.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 120.0, 118.0, 177.0, 118.0, 121.0, 145.0, 116.0, 158.0, 122.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 159.0, 159.0, 160.0, 160.0, 159.0, 161.0, 162.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 112.0, 74.0, 76.0, 73.0, 74.0, 99.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 117.0, 84.0, 84.0, 83.0, 128.0, 83.0, 84.0, 120.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 147.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 52.0, 53.0, 53.0, 55.0, 52.0, 55.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 61.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 75.0, 75.0, 75.0, 74.0, 81.0, 81.0, 80.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 118.0, 120.0, 127.0, 118.0, 125.0, 118.0, 120.0, 193.0, 134.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 162.0, 158.0, 156.0, 156.0, 158.0, 158.0, 158.0, 154.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 62.0, 65.0, 63.0, 64.0, 63.0, 63.0, 62.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1480.6000000000001, "alertThreshold": 2.0}]} 05:40:41 INFO - Return code: 0 05:40:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:41 INFO - # TBPL SUCCESS # 05:40:41 INFO - Running post-action listener: _resource_record_post_action 05:40:41 INFO - [mozharness: 2016-07-20 12:40:41.972746Z] Finished run-tests step (success) 05:40:41 INFO - Running post-run listener: _resource_record_post_run 05:40:42 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 554205184; Read time: 44; Write time: 442608 05:40:42 INFO - TinderboxPrint: CPU usage
13.0% 05:40:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 44 05:40:42 INFO - TinderboxPrint: I/O write bytes / time
554,205,184 / 442,608 05:40:42 INFO - TinderboxPrint: CPU idle
8,273.5 (87.0%) 05:40:42 INFO - TinderboxPrint: CPU user
1,194.0 (12.6%) 05:40:42 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:42 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257630208; Read time: 0; Write time: 268236 05:40:42 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 296574976; Read time: 0; Write time: 174372 05:40:42 INFO - Running post-run listener: _upload_blobber_files 05:40:42 INFO - Blob upload gear active. 05:40:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:40:42 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:40:42 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:40:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:40:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:40:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:44 INFO - (blobuploader) - INFO - Done attempting. 05:40:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:40:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:40:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:45 INFO - (blobuploader) - INFO - Done attempting. 05:40:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:41:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:08 INFO - (blobuploader) - INFO - Done attempting. 05:41:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:41:08 INFO - Return code: 0 05:41:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:41:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:41:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b6be2993fd92ec462b983c0a56e2e2a1304dbada479a5a7fe14502379a15d90856af0dfd18f800ffc74dcac58336d87af0a0295c1860d93db78ab5b355c593e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1513f9e8e5d551ed45cb267c2d516d0fed1b93096754bf46b503355ce9c9e5b50c2012e9b9f581432a6f1cb656695b5215eaa491f814f965edd910537f9b2c84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d3d397903cadde5f682452419a426ba7598073c8a4077ef5e1b80891e13e452893bbccdf6a83bdfc5f4cdd3e1784f43c21ed0260d57aa3ada1cd34953011c4b"} 05:41:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:41:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:41:08 INFO - Contents: 05:41:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b6be2993fd92ec462b983c0a56e2e2a1304dbada479a5a7fe14502379a15d90856af0dfd18f800ffc74dcac58336d87af0a0295c1860d93db78ab5b355c593e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1513f9e8e5d551ed45cb267c2d516d0fed1b93096754bf46b503355ce9c9e5b50c2012e9b9f581432a6f1cb656695b5215eaa491f814f965edd910537f9b2c84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d3d397903cadde5f682452419a426ba7598073c8a4077ef5e1b80891e13e452893bbccdf6a83bdfc5f4cdd3e1784f43c21ed0260d57aa3ada1cd34953011c4b"} 05:41:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:41:08 INFO - Copying logs to upload dir... 05:41:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:41:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.035871 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-20 05:41:08.959489) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 05:41:08.962832) ========= 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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b6be2993fd92ec462b983c0a56e2e2a1304dbada479a5a7fe14502379a15d90856af0dfd18f800ffc74dcac58336d87af0a0295c1860d93db78ab5b355c593e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1513f9e8e5d551ed45cb267c2d516d0fed1b93096754bf46b503355ce9c9e5b50c2012e9b9f581432a6f1cb656695b5215eaa491f814f965edd910537f9b2c84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d3d397903cadde5f682452419a426ba7598073c8a4077ef5e1b80891e13e452893bbccdf6a83bdfc5f4cdd3e1784f43c21ed0260d57aa3ada1cd34953011c4b"} build_url:https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/J_Nqahx5S5GUT_YikbDSiA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b6be2993fd92ec462b983c0a56e2e2a1304dbada479a5a7fe14502379a15d90856af0dfd18f800ffc74dcac58336d87af0a0295c1860d93db78ab5b355c593e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1513f9e8e5d551ed45cb267c2d516d0fed1b93096754bf46b503355ce9c9e5b50c2012e9b9f581432a6f1cb656695b5215eaa491f814f965edd910537f9b2c84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d3d397903cadde5f682452419a426ba7598073c8a4077ef5e1b80891e13e452893bbccdf6a83bdfc5f4cdd3e1784f43c21ed0260d57aa3ada1cd34953011c4b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 05:41:08.993308) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:41:08.993856) ========= 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=7de08804244eb36560b44971000001c3-1469015394.979264-2106357212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:41:09.079389) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 05:41:09.079953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 05:41:09.080518) ========= ========= Total master_lag: 0.19 =========