builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1469184483.65 results: success (0) buildid: 20160722030125 builduid: 829d47c6d12349219d4c3cb6675dcc53 revision: e0bc88708ffed39aaab1fbc0ac461d93561195de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:03.647404) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:03.648032) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:03.648337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:03.665061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:03.665742) ========= 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=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 03:48:04-- 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% 43.4M=0s 2016-07-22 03:48:04 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.336081 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:04.016635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:04.016999) ========= 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=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019046 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:04.067435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 03:48:04.067945) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e0bc88708ffed39aaab1fbc0ac461d93561195de --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 e0bc88708ffed39aaab1fbc0ac461d93561195de --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=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 03:48:04,633 truncating revision to first 12 chars 2016-07-22 03:48:04,633 Setting DEBUG logging. 2016-07-22 03:48:04,633 attempt 1/10 2016-07-22 03:48:04,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e0bc88708ffe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 03:48:06,020 unpacking tar archive at: fx-team-e0bc88708ffe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.719464 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 03:48:05.801381) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:05.801804) ========= script_repo_revision: e0bc88708ffed39aaab1fbc0ac461d93561195de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:05.802221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:05.802486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 03:48:05.811243) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-22 03:48:05.811633) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:48:06 INFO - MultiFileLogger online at 20160722 03:48:06 in /builds/slave/test 03:48:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:48:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:48:06 INFO - {'append_to_log': False, 03:48:06 INFO - 'base_work_dir': '/builds/slave/test', 03:48:06 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:48:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:48:06 INFO - 'branch': 'Fx-Team-Non-PGO', 03:48:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:48:06 INFO - 'config_files': ('talos/linux_config.py',), 03:48:06 INFO - 'default_actions': ('clobber', 03:48:06 INFO - 'read-buildbot-config', 03:48:06 INFO - 'download-and-extract', 03:48:06 INFO - 'populate-webroot', 03:48:06 INFO - 'create-virtualenv', 03:48:06 INFO - 'install', 03:48:06 INFO - 'run-tests'), 03:48:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:48:06 INFO - 'download_minidump_stackwalk': True, 03:48:06 INFO - 'download_symbols': 'ondemand', 03:48:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:48:06 INFO - 'tooltool.py': '/tools/tooltool.py', 03:48:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:48:06 INFO - '/tools/misc-python/virtualenv.py')}, 03:48:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:48:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:48:06 INFO - 'installer_path': 'installer.exe', 03:48:06 INFO - 'log_level': 'info', 03:48:06 INFO - 'log_name': 'talos', 03:48:06 INFO - 'log_to_console': True, 03:48:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:48:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:48:06 INFO - 'opt_config_files': (), 03:48:06 INFO - 'pip_index': False, 03:48:06 INFO - 'sps_profile': False, 03:48:06 INFO - 'sps_profile_interval': 0, 03:48:06 INFO - 'suite': 'dromaeojs', 03:48:06 INFO - 'system_bits': '32', 03:48:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:48:06 INFO - 'title': 'talos-linux64-ix-021', 03:48:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:48:06 INFO - 'use_talos_json': True, 03:48:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:48:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:48:06 INFO - 'work_dir': 'build'} 03:48:06 INFO - [mozharness: 2016-07-22 10:48:06.470386Z] Running clobber step. 03:48:06 INFO - Running pre-action listener: _resource_record_pre_action 03:48:06 INFO - Running main action method: clobber 03:48:06 INFO - rmtree: /builds/slave/test/build 03:48:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:48:06 INFO - Running post-action listener: _resource_record_post_action 03:48:06 INFO - [mozharness: 2016-07-22 10:48:06.948504Z] Finished clobber step (success) 03:48:06 INFO - [mozharness: 2016-07-22 10:48:06.948605Z] Running read-buildbot-config step. 03:48:06 INFO - Running pre-action listener: _resource_record_pre_action 03:48:06 INFO - Running main action method: read_buildbot_config 03:48:06 INFO - Using buildbot properties: 03:48:06 INFO - { 03:48:06 INFO - "project": "", 03:48:06 INFO - "product": "firefox", 03:48:06 INFO - "script_repo_revision": "production", 03:48:06 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:48:06 INFO - "repository": "", 03:48:06 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 03:48:06 INFO - "buildid": "20160722030125", 03:48:06 INFO - "pgo_build": "False", 03:48:06 INFO - "basedir": "/builds/slave/test", 03:48:06 INFO - "buildnumber": 2007, 03:48:06 INFO - "slavename": "talos-linux64-ix-021", 03:48:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:48:06 INFO - "platform": "ubuntu64_hw", 03:48:06 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:48:06 INFO - "branch": "fx-team", 03:48:06 INFO - "repo_path": "integration/fx-team", 03:48:06 INFO - "revision": "e0bc88708ffed39aaab1fbc0ac461d93561195de", 03:48:06 INFO - "stage_platform": "linux64", 03:48:06 INFO - "builduid": "829d47c6d12349219d4c3cb6675dcc53", 03:48:06 INFO - "slavebuilddir": "test" 03:48:06 INFO - } 03:48:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 03:48:06 INFO - Running post-action listener: _resource_record_post_action 03:48:06 INFO - [mozharness: 2016-07-22 10:48:06.951055Z] Finished read-buildbot-config step (success) 03:48:06 INFO - [mozharness: 2016-07-22 10:48:06.951144Z] Running download-and-extract step. 03:48:06 INFO - Running pre-action listener: _resource_record_pre_action 03:48:06 INFO - Running main action method: download_and_extract 03:48:06 INFO - mkdir: /builds/slave/test/build/tests 03:48:06 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')]} 03:48:06 INFO - https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:48:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:48:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:48:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:10 INFO - Downloaded 1361 bytes. 03:48:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:48:10 INFO - Using the following test package requirements: 03:48:10 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:48:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:48:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:48:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'jsshell-linux-x86_64.zip'], 03:48:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:48:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:48:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:48:10 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:48:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:48:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:48:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:48:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:48:10 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')]} 03:48:10 INFO - https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:48:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:48:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:48:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:11 INFO - Downloaded 22737593 bytes. 03:48:11 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'] 03:48:11 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 03:48:12 INFO - Return code: 0 03:48:12 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 03:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:12 INFO - https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:48:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:48:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:48:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:13 INFO - Downloaded 22737593 bytes. 03:48:13 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'] 03:48:13 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 03:48:15 INFO - Return code: 0 03:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:15 INFO - https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:48:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:48:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:48:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:16 INFO - Downloaded 14403289 bytes. 03:48:16 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'] 03:48:16 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 03:48:16 INFO - Return code: 0 03:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:48:16 INFO - https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:48:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:48:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:48:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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 03:48:17 INFO - Downloaded 57160189 bytes. 03:48:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:48:17 INFO - mkdir: /builds/slave/test/properties 03:48:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:48:17 INFO - Writing to file /builds/slave/test/properties/build_url 03:48:17 INFO - Contents: 03:48:17 INFO - build_url:https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:48:18 INFO - Running post-action listener: _resource_record_post_action 03:48:18 INFO - Running post-action listener: set_extra_try_arguments 03:48:18 INFO - [mozharness: 2016-07-22 10:48:18.606028Z] Finished download-and-extract step (success) 03:48:18 INFO - [mozharness: 2016-07-22 10:48:18.606260Z] Running populate-webroot step. 03:48:18 INFO - Running pre-action listener: _resource_record_pre_action 03:48:18 INFO - Running main action method: populate_webroot 03:48:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:48:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:48:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:48:18 INFO - '--tppagecycles', 03:48:18 INFO - '7'], 03:48:18 INFO - 'tests': ['tsvgm']}}, 03:48:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:48:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:48:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:48:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:48:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:48:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:48:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['damp', 'tps']}, 03:48:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'tests': ['damp', 'tps']}, 03:48:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['dromaeo_dom']}, 03:48:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:48:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['basic_compositor_video']}, 03:48:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:48:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'other-e10s': {'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['a11yr', 03:48:18 INFO - 'ts_paint', 03:48:18 INFO - 'tpaint', 03:48:18 INFO - 'sessionrestore', 03:48:18 INFO - 'sessionrestore_no_auto_restore', 03:48:18 INFO - 'tabpaint']}, 03:48:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['tsvgx', 03:48:18 INFO - 'tsvgr_opacity', 03:48:18 INFO - 'tart', 03:48:18 INFO - 'tscrollx', 03:48:18 INFO - 'cart']}, 03:48:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:48:18 INFO - 'tsvgr_opacity', 03:48:18 INFO - 'tart', 03:48:18 INFO - 'tscrollx', 03:48:18 INFO - 'cart']}, 03:48:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'talos_options': ['--disable-e10s'], 03:48:18 INFO - 'tests': ['tp5o']}, 03:48:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'tests': ['tp5o']}, 03:48:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'talos_options': ['--disable-e10s', 03:48:18 INFO - '--xperf_path', 03:48:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:48:18 INFO - 'tests': ['tp5n']}, 03:48:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:48:18 INFO - 'talos_options': ['--xperf_path', 03:48:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:48:18 INFO - 'tests': ['tp5n']}}, 03:48:18 INFO - 'talos.zip': {'path': '', 03:48:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:48:18 INFO - Running post-action listener: _resource_record_post_action 03:48:18 INFO - [mozharness: 2016-07-22 10:48:18.624072Z] Finished populate-webroot step (success) 03:48:18 INFO - [mozharness: 2016-07-22 10:48:18.624161Z] Running create-virtualenv step. 03:48:18 INFO - Running pre-action listener: _resource_record_pre_action 03:48:18 INFO - Running main action method: create_virtualenv 03:48:18 INFO - Creating virtualenv /builds/slave/test/build/venv 03:48:18 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 03:48:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:48:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:48:18 INFO - Using real prefix '/usr' 03:48:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:48:19 INFO - Installing distribute.............................................................................................................................................................................................done. 03:48:21 INFO - Installing pip.................done. 03:48:21 INFO - Return code: 0 03:48:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:21 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:21 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 03:48:21 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 03:48:21 INFO - Using env: {'DISPLAY': ':0', 03:48:21 INFO - 'HOME': '/home/cltbld', 03:48:21 INFO - 'LANG': 'en_US.UTF-8', 03:48:21 INFO - 'LANGUAGE': 'en_US:en', 03:48:21 INFO - 'LOGNAME': 'cltbld', 03:48:21 INFO - 'MAIL': '/var/mail/cltbld', 03:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:21 INFO - 'MOZ_NO_REMOTE': '1', 03:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:21 INFO - 'NO_EM_RESTART': '1', 03:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:21 INFO - 'PWD': '/builds/slave/test', 03:48:21 INFO - 'SHELL': '/bin/bash', 03:48:21 INFO - 'SHLVL': '1', 03:48:21 INFO - 'TERM': 'linux', 03:48:21 INFO - 'TMOUT': '86400', 03:48:21 INFO - 'USER': 'cltbld', 03:48:21 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:21 INFO - '_': '/tools/buildbot/bin/python'} 03:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:21 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 03:48:21 INFO - Cleaning up... 03:48:21 INFO - Return code: 0 03:48:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:21 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:21 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 03:48:21 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 03:48:21 INFO - Using env: {'DISPLAY': ':0', 03:48:21 INFO - 'HOME': '/home/cltbld', 03:48:21 INFO - 'LANG': 'en_US.UTF-8', 03:48:21 INFO - 'LANGUAGE': 'en_US:en', 03:48:21 INFO - 'LOGNAME': 'cltbld', 03:48:21 INFO - 'MAIL': '/var/mail/cltbld', 03:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:21 INFO - 'MOZ_NO_REMOTE': '1', 03:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:21 INFO - 'NO_EM_RESTART': '1', 03:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:21 INFO - 'PWD': '/builds/slave/test', 03:48:21 INFO - 'SHELL': '/bin/bash', 03:48:21 INFO - 'SHLVL': '1', 03:48:21 INFO - 'TERM': 'linux', 03:48:21 INFO - 'TMOUT': '86400', 03:48:21 INFO - 'USER': 'cltbld', 03:48:21 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:21 INFO - '_': '/tools/buildbot/bin/python'} 03:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:21 INFO - Downloading/unpacking psutil>=3.1.1 03:48:21 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 03:48:21 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 03:48:21 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 03:48:21 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 03:48:21 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 03:48:21 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 03:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:48:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:48:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:48:23 INFO - Installing collected packages: psutil 03:48:23 INFO - Running setup.py install for psutil 03:48:23 INFO - building 'psutil._psutil_linux' extension 03:48:23 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 03:48:24 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 03:48:24 INFO - building 'psutil._psutil_posix' extension 03:48:24 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 03:48:24 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 03:48:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:48:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:48:24 INFO - Successfully installed psutil 03:48:24 INFO - Cleaning up... 03:48:24 INFO - Return code: 0 03:48:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:48:24 INFO - Using env: {'DISPLAY': ':0', 03:48:24 INFO - 'HOME': '/home/cltbld', 03:48:24 INFO - 'LANG': 'en_US.UTF-8', 03:48:24 INFO - 'LANGUAGE': 'en_US:en', 03:48:24 INFO - 'LOGNAME': 'cltbld', 03:48:24 INFO - 'MAIL': '/var/mail/cltbld', 03:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:24 INFO - 'MOZ_NO_REMOTE': '1', 03:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:24 INFO - 'NO_EM_RESTART': '1', 03:48:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:24 INFO - 'PWD': '/builds/slave/test', 03:48:24 INFO - 'SHELL': '/bin/bash', 03:48:24 INFO - 'SHLVL': '1', 03:48:24 INFO - 'TERM': 'linux', 03:48:24 INFO - 'TMOUT': '86400', 03:48:24 INFO - 'USER': 'cltbld', 03:48:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:24 INFO - '_': '/tools/buildbot/bin/python'} 03:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:48:26 INFO - Installing collected packages: mozsystemmonitor 03:48:26 INFO - Running setup.py install for mozsystemmonitor 03:48:26 INFO - Successfully installed mozsystemmonitor 03:48:26 INFO - Cleaning up... 03:48:26 INFO - Return code: 0 03:48:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:48:26 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')]} 03:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:26 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')]} 03:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:26 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:26 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 03:48:26 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 03:48:26 INFO - Using env: {'DISPLAY': ':0', 03:48:26 INFO - 'HOME': '/home/cltbld', 03:48:26 INFO - 'LANG': 'en_US.UTF-8', 03:48:26 INFO - 'LANGUAGE': 'en_US:en', 03:48:26 INFO - 'LOGNAME': 'cltbld', 03:48:26 INFO - 'MAIL': '/var/mail/cltbld', 03:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:26 INFO - 'MOZ_NO_REMOTE': '1', 03:48:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:26 INFO - 'NO_EM_RESTART': '1', 03:48:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:26 INFO - 'PWD': '/builds/slave/test', 03:48:26 INFO - 'SHELL': '/bin/bash', 03:48:26 INFO - 'SHLVL': '1', 03:48:26 INFO - 'TERM': 'linux', 03:48:26 INFO - 'TMOUT': '86400', 03:48:26 INFO - 'USER': 'cltbld', 03:48:26 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:26 INFO - '_': '/tools/buildbot/bin/python'} 03:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:26 INFO - Downloading/unpacking jsonschema==2.5.1 03:48:26 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 03:48:26 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 03:48:26 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 03:48:26 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 03:48:26 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 03:48:26 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 03:48:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:48:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:48:28 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 03:48:28 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 03:48:28 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 03:48:28 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 03:48:28 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 03:48:28 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 03:48:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:48:29 INFO - warning: no files found matching '*.txt' 03:48:29 INFO - no previously-included directories found matching 'build' 03:48:29 INFO - no previously-included directories found matching 'dist' 03:48:29 INFO - no previously-included directories found matching '.git*' 03:48:29 INFO - Installing collected packages: jsonschema, functools32 03:48:29 INFO - Running setup.py install for functools32 03:48:29 INFO - warning: no files found matching '*.txt' 03:48:29 INFO - no previously-included directories found matching 'build' 03:48:29 INFO - no previously-included directories found matching 'dist' 03:48:29 INFO - no previously-included directories found matching '.git*' 03:48:29 INFO - Successfully installed jsonschema functools32 03:48:29 INFO - Cleaning up... 03:48:29 INFO - Return code: 0 03:48:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:48:29 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')]} 03:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:29 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')]} 03:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:29 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:29 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 03:48:29 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 03:48:29 INFO - Using env: {'DISPLAY': ':0', 03:48:29 INFO - 'HOME': '/home/cltbld', 03:48:29 INFO - 'LANG': 'en_US.UTF-8', 03:48:29 INFO - 'LANGUAGE': 'en_US:en', 03:48:29 INFO - 'LOGNAME': 'cltbld', 03:48:29 INFO - 'MAIL': '/var/mail/cltbld', 03:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:29 INFO - 'MOZ_NO_REMOTE': '1', 03:48:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:29 INFO - 'NO_EM_RESTART': '1', 03:48:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:29 INFO - 'PWD': '/builds/slave/test', 03:48:29 INFO - 'SHELL': '/bin/bash', 03:48:29 INFO - 'SHLVL': '1', 03:48:29 INFO - 'TERM': 'linux', 03:48:29 INFO - 'TMOUT': '86400', 03:48:29 INFO - 'USER': 'cltbld', 03:48:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:29 INFO - '_': '/tools/buildbot/bin/python'} 03:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:29 INFO - Downloading/unpacking blobuploader==1.2.4 03:48:29 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 03:48:29 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 03:48:29 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 03:48:29 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 03:48:29 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 03:48:29 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 03:48:31 INFO - Downloading blobuploader-1.2.4.tar.gz 03:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:48:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:48:31 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 03:48:31 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 03:48:31 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 03:48:31 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 03:48:31 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 03:48:31 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 03:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:48:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:48:32 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 03:48:32 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 03:48:32 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 03:48:32 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 03:48:32 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 03:48:32 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 03:48:32 INFO - Downloading docopt-0.6.1.tar.gz 03:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:48:32 INFO - Installing collected packages: blobuploader, requests, docopt 03:48:32 INFO - Running setup.py install for blobuploader 03:48:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:48:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:48:32 INFO - Running setup.py install for requests 03:48:32 INFO - Running setup.py install for docopt 03:48:32 INFO - Successfully installed blobuploader requests docopt 03:48:32 INFO - Cleaning up... 03:48:32 INFO - Return code: 0 03:48:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48:32 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')]} 03:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:32 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')]} 03:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:32 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:32 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 03:48:32 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 03:48:32 INFO - Using env: {'DISPLAY': ':0', 03:48:32 INFO - 'HOME': '/home/cltbld', 03:48:32 INFO - 'LANG': 'en_US.UTF-8', 03:48:32 INFO - 'LANGUAGE': 'en_US:en', 03:48:32 INFO - 'LOGNAME': 'cltbld', 03:48:32 INFO - 'MAIL': '/var/mail/cltbld', 03:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:32 INFO - 'MOZ_NO_REMOTE': '1', 03:48:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:32 INFO - 'NO_EM_RESTART': '1', 03:48:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:32 INFO - 'PWD': '/builds/slave/test', 03:48:32 INFO - 'SHELL': '/bin/bash', 03:48:32 INFO - 'SHLVL': '1', 03:48:32 INFO - 'TERM': 'linux', 03:48:32 INFO - 'TMOUT': '86400', 03:48:32 INFO - 'USER': 'cltbld', 03:48:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:32 INFO - '_': '/tools/buildbot/bin/python'} 03:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:48:33 INFO - Running setup.py (path:/tmp/pip-Qg7tD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:48:33 INFO - Running setup.py (path:/tmp/pip-CHY_77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:48:33 INFO - Running setup.py (path:/tmp/pip-HwWKQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:48:33 INFO - Running setup.py (path:/tmp/pip-ovp8qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:48:33 INFO - Running setup.py (path:/tmp/pip-GnKj5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:48:33 INFO - Running setup.py (path:/tmp/pip-F2XbL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:48:33 INFO - Running setup.py (path:/tmp/pip-dpqyA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:48:33 INFO - Running setup.py (path:/tmp/pip-JbvJ1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:48:33 INFO - Running setup.py (path:/tmp/pip-qUx6gP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:48:33 INFO - Running setup.py (path:/tmp/pip-CfQrIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:48:33 INFO - Running setup.py (path:/tmp/pip-pBdlBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:48:33 INFO - Running setup.py (path:/tmp/pip-Q9P7RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:48:34 INFO - Running setup.py (path:/tmp/pip-NIeN88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:48:34 INFO - Running setup.py (path:/tmp/pip-bXyYxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:34 INFO - Running setup.py (path:/tmp/pip-Kosmgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:48:34 INFO - Running setup.py (path:/tmp/pip-Cnn14n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:48:34 INFO - Running setup.py (path:/tmp/pip-OZLFRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:48:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:48:34 INFO - Running setup.py install for manifestparser 03:48:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:48:34 INFO - Running setup.py install for mozcrash 03:48:34 INFO - Running setup.py install for mozdebug 03:48:34 INFO - Running setup.py install for mozdevice 03:48:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:48:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:48:34 INFO - Running setup.py install for mozfile 03:48:34 INFO - Running setup.py install for mozhttpd 03:48:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:48:34 INFO - Running setup.py install for mozinfo 03:48:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for mozInstall 03:48:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for mozleak 03:48:35 INFO - Running setup.py install for mozlog 03:48:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for moznetwork 03:48:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for mozprocess 03:48:35 INFO - Running setup.py install for mozprofile 03:48:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for mozrunner 03:48:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:48:35 INFO - Running setup.py install for mozscreenshot 03:48:35 INFO - Running setup.py install for moztest 03:48:36 INFO - Running setup.py install for mozversion 03:48:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:48:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:48:36 INFO - Cleaning up... 03:48:36 INFO - Return code: 0 03:48:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48:36 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')]} 03:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:36 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')]} 03:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:36 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:36 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 03:48:36 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 03:48:36 INFO - Using env: {'DISPLAY': ':0', 03:48:36 INFO - 'HOME': '/home/cltbld', 03:48:36 INFO - 'LANG': 'en_US.UTF-8', 03:48:36 INFO - 'LANGUAGE': 'en_US:en', 03:48:36 INFO - 'LOGNAME': 'cltbld', 03:48:36 INFO - 'MAIL': '/var/mail/cltbld', 03:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:36 INFO - 'MOZ_NO_REMOTE': '1', 03:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:36 INFO - 'NO_EM_RESTART': '1', 03:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:36 INFO - 'PWD': '/builds/slave/test', 03:48:36 INFO - 'SHELL': '/bin/bash', 03:48:36 INFO - 'SHLVL': '1', 03:48:36 INFO - 'TERM': 'linux', 03:48:36 INFO - 'TMOUT': '86400', 03:48:36 INFO - 'USER': 'cltbld', 03:48:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:36 INFO - '_': '/tools/buildbot/bin/python'} 03:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:48:36 INFO - Running setup.py (path:/tmp/pip-uf_h9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:48:36 INFO - Running setup.py (path:/tmp/pip-xzKRZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:48:36 INFO - Running setup.py (path:/tmp/pip-lGLLY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:48:36 INFO - Running setup.py (path:/tmp/pip-tP12Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:48:36 INFO - Running setup.py (path:/tmp/pip-m0uAAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:48:36 INFO - Running setup.py (path:/tmp/pip-Fu4VJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:48:36 INFO - Running setup.py (path:/tmp/pip-MxxRLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:48:36 INFO - Running setup.py (path:/tmp/pip-dtXrtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:48:36 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)) 03:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:48:36 INFO - Running setup.py (path:/tmp/pip-ICr9af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:48:37 INFO - Running setup.py (path:/tmp/pip-qgAugq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:48:37 INFO - Running setup.py (path:/tmp/pip-PRIuln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:48:37 INFO - Running setup.py (path:/tmp/pip-GBDH7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:48:37 INFO - Running setup.py (path:/tmp/pip-tGy1pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:48:37 INFO - Running setup.py (path:/tmp/pip-eciKdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:37 INFO - Running setup.py (path:/tmp/pip-PTtgOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:48:37 INFO - Running setup.py (path:/tmp/pip-Q22rMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:48:37 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)) 03:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:48:37 INFO - Running setup.py (path:/tmp/pip-UsNBd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:48:37 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)) 03:48:37 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)) 03:48:37 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)) 03:48:37 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)) 03:48:37 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)) 03:48:37 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)) 03:48:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48:39 INFO - Downloading blessings-1.6.tar.gz 03:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:48:39 INFO - Installing collected packages: blessings 03:48:39 INFO - Running setup.py install for blessings 03:48:39 INFO - Successfully installed blessings 03:48:39 INFO - Cleaning up... 03:48:39 INFO - Return code: 0 03:48:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:40 INFO - Reading from file tmpfile_stdout 03:48:40 INFO - Current package versions: 03:48:40 INFO - argparse == 1.2.1 03:48:40 INFO - blessings == 1.6 03:48:40 INFO - blobuploader == 1.2.4 03:48:40 INFO - docopt == 0.6.1 03:48:40 INFO - functools32 == 3.2.3-2 03:48:40 INFO - jsonschema == 2.5.1 03:48:40 INFO - manifestparser == 1.1 03:48:40 INFO - mozInstall == 1.12 03:48:40 INFO - mozcrash == 0.17 03:48:40 INFO - mozdebug == 0.1 03:48:40 INFO - mozdevice == 0.48 03:48:40 INFO - mozfile == 1.2 03:48:40 INFO - mozhttpd == 0.7 03:48:40 INFO - mozinfo == 0.9 03:48:40 INFO - mozleak == 0.1 03:48:40 INFO - mozlog == 3.3 03:48:40 INFO - moznetwork == 0.27 03:48:40 INFO - mozprocess == 0.23 03:48:40 INFO - mozprofile == 0.28 03:48:40 INFO - mozrunner == 6.12 03:48:40 INFO - mozscreenshot == 0.1 03:48:40 INFO - mozsystemmonitor == 0.3 03:48:40 INFO - moztest == 0.7 03:48:40 INFO - mozversion == 1.4 03:48:40 INFO - psutil == 3.1.1 03:48:40 INFO - requests == 1.2.3 03:48:40 INFO - wsgiref == 0.1.2 03:48:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48:40 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')]} 03:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:40 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')]} 03:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:40 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:40 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 03:48:40 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 03:48:40 INFO - Using env: {'DISPLAY': ':0', 03:48:40 INFO - 'HOME': '/home/cltbld', 03:48:40 INFO - 'LANG': 'en_US.UTF-8', 03:48:40 INFO - 'LANGUAGE': 'en_US:en', 03:48:40 INFO - 'LOGNAME': 'cltbld', 03:48:40 INFO - 'MAIL': '/var/mail/cltbld', 03:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:40 INFO - 'MOZ_NO_REMOTE': '1', 03:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:40 INFO - 'NO_EM_RESTART': '1', 03:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:40 INFO - 'PWD': '/builds/slave/test', 03:48:40 INFO - 'SHELL': '/bin/bash', 03:48:40 INFO - 'SHLVL': '1', 03:48:40 INFO - 'TERM': 'linux', 03:48:40 INFO - 'TMOUT': '86400', 03:48:40 INFO - 'USER': 'cltbld', 03:48:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:40 INFO - '_': '/tools/buildbot/bin/python'} 03:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 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)) 03:48:40 INFO - Cleaning up... 03:48:40 INFO - Return code: 0 03:48:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:48:40 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')]} 03:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:40 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')]} 03:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:40 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2887180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, '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': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', '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 03:48:40 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 03:48:40 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 03:48:40 INFO - Using env: {'DISPLAY': ':0', 03:48:40 INFO - 'HOME': '/home/cltbld', 03:48:40 INFO - 'LANG': 'en_US.UTF-8', 03:48:40 INFO - 'LANGUAGE': 'en_US:en', 03:48:40 INFO - 'LOGNAME': 'cltbld', 03:48:40 INFO - 'MAIL': '/var/mail/cltbld', 03:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:40 INFO - 'MOZ_NO_REMOTE': '1', 03:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:40 INFO - 'NO_EM_RESTART': '1', 03:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:40 INFO - 'PWD': '/builds/slave/test', 03:48:40 INFO - 'SHELL': '/bin/bash', 03:48:40 INFO - 'SHLVL': '1', 03:48:40 INFO - 'TERM': 'linux', 03:48:40 INFO - 'TMOUT': '86400', 03:48:40 INFO - 'USER': 'cltbld', 03:48:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:40 INFO - '_': '/tools/buildbot/bin/python'} 03:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:48:40 INFO - Cleaning up... 03:48:40 INFO - Return code: 0 03:48:40 INFO - Running post-action listener: _resource_record_post_action 03:48:40 INFO - Running post-action listener: _start_resource_monitoring 03:48:40 INFO - Starting resource monitoring. 03:48:40 INFO - [mozharness: 2016-07-22 10:48:40.481685Z] Finished create-virtualenv step (success) 03:48:40 INFO - [mozharness: 2016-07-22 10:48:40.482132Z] Running install step. 03:48:40 INFO - Running pre-action listener: _resource_record_pre_action 03:48:40 INFO - Running main action method: install 03:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:40 INFO - Reading from file tmpfile_stdout 03:48:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:48:40 INFO - Reading from file tmpfile_stdout 03:48:40 INFO - Output received: 03:48:40 INFO - Usage: mozinstall [options] installer 03:48:40 INFO - Options: 03:48:40 INFO - -h, --help show this help message and exit 03:48:40 INFO - -d DEST, --destination=DEST 03:48:40 INFO - Directory to install application into. [default: 03:48:40 INFO - "/builds/slave/test"] 03:48:40 INFO - --app=APP Application being installed. [default: firefox] 03:48:40 INFO - mkdir: /builds/slave/test/build/application 03:48:40 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'] 03:48:40 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 03:48:57 INFO - Reading from file tmpfile_stdout 03:48:57 INFO - Output received: 03:48:57 INFO - /builds/slave/test/build/application/firefox/firefox 03:48:57 INFO - Running post-action listener: _resource_record_post_action 03:48:57 INFO - [mozharness: 2016-07-22 10:48:57.865212Z] Finished install step (success) 03:48:57 INFO - [mozharness: 2016-07-22 10:48:57.865580Z] Running run-tests step. 03:48:57 INFO - Running pre-action listener: _resource_record_pre_action 03:48:57 INFO - Running main action method: run_tests 03:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:48:57 INFO - Python 2.7.3 03:48:57 INFO - Return code: 0 03:48:57 INFO - grabbing minidump binary from tooltool 03:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:57 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 0x28853b0>, '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 0x28862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2886740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:48:57 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 03:48:57 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 03:48:57 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 03:48:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:48:57 INFO - Return code: 0 03:48:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:48:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:48:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:48:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:48:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 03:48:57 INFO - Using env: {'DISPLAY': ':0', 03:48:57 INFO - 'HOME': '/home/cltbld', 03:48:57 INFO - 'LANG': 'en_US.UTF-8', 03:48:57 INFO - 'LANGUAGE': 'en_US:en', 03:48:57 INFO - 'LOGNAME': 'cltbld', 03:48:57 INFO - 'MAIL': '/var/mail/cltbld', 03:48:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:57 INFO - 'MOZ_NO_REMOTE': '1', 03:48:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:57 INFO - 'NO_EM_RESTART': '1', 03:48:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:57 INFO - 'PWD': '/builds/slave/test', 03:48:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:57 INFO - 'SHELL': '/bin/bash', 03:48:57 INFO - 'SHLVL': '1', 03:48:57 INFO - 'TERM': 'linux', 03:48:57 INFO - 'TMOUT': '86400', 03:48:57 INFO - 'USER': 'cltbld', 03:48:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247', 03:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:57 INFO - '_': '/tools/buildbot/bin/python'} 03:48:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:48:58 INFO - mozversion application_buildid: 20160722030125 03:48:58 INFO - mozversion application_changeset: e0bc88708ffed39aaab1fbc0ac461d93561195de 03:48:58 INFO - mozversion application_display_name: Nightly 03:48:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:48:58 INFO - mozversion application_name: Firefox 03:48:58 INFO - mozversion application_remotingname: firefox 03:48:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:48:58 INFO - mozversion application_vendor: Mozilla 03:48:58 INFO - mozversion application_version: 50.0a1 03:48:58 INFO - mozversion platform_buildid: 20160722030125 03:48:58 INFO - mozversion platform_changeset: e0bc88708ffed39aaab1fbc0ac461d93561195de 03:48:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:48:58 INFO - mozversion platform_version: 50.0a1 03:48:58 INFO - using testdate: 1469184538 03:48:58 INFO - actual date: 1469184538 03:48:58 INFO - starting webserver on 'localhost:41546' 03:48:58 INFO - SUITE-START | Running 2 tests 03:48:58 INFO - TEST-START | dromaeo_css 03:48:58 INFO - operating with platform_type : linux_ 03:48:58 INFO - Initialising browser for dromaeo_css test... 03:48:58 INFO - TEST-INFO | started process 9639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp65GHzH/profile http://localhost:41546/getInfo.html) 03:49:02 INFO - PROCESS | 9639 | JavaScript error: , line 0: uncaught exception: undefined 03:49:02 INFO - PROCESS | 9639 | __metrics Screen width/height:1600/1200 03:49:02 INFO - PROCESS | 9639 | colorDepth:24 03:49:02 INFO - PROCESS | 9639 | Browser inner width/height: 1024/697 03:49:02 INFO - PROCESS | 9639 | __metrics 03:49:03 INFO - PROCESS | 9639 | 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" 03:49:03 INFO - PROCESS | 9639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:49:04 INFO - PROCESS | 9639 | console.error: PushService: 03:49:04 INFO - PROCESS | 9639 | stateChangeProcessEnqueue: Error transitioning state 03:49:04 INFO - PROCESS | 9639 | UnknownError 03:49:04 INFO - TEST-INFO | 9639: exit 0 03:49:04 INFO - Browser initialized. 03:49:04 INFO - Running cycle 1/1 for dromaeo_css test... 03:49:04 INFO - TEST-INFO | started process 9718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp65GHzH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:17 INFO - PROCESS | 9718 | RSS: Main: 159551488 03:49:17 INFO - PROCESS | 9718 | 03:50:08 INFO - PROCESS | 9718 | 1469184608679 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] 03:52:20 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41546/tests/dromaeo/cssquery-ext.html) 03:52:22 INFO - PROCESS | 9718 | RSS: Main: 248270848 03:52:22 INFO - PROCESS | 9718 | 03:55:24 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-ext.html (next: http://localhost:41546/tests/dromaeo/cssquery-jquery.html) 03:55:25 INFO - PROCESS | 9718 | RSS: Main: 246022144 03:55:25 INFO - PROCESS | 9718 | 03:58:27 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41546/tests/dromaeo/cssquery-mootools.html) 03:58:28 INFO - PROCESS | 9718 | RSS: Main: 217927680 03:58:28 INFO - PROCESS | 9718 | 04:01:30 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41546/tests/dromaeo/cssquery-prototype.html) 04:01:30 INFO - PROCESS | 9718 | RSS: Main: 187768832 04:01:30 INFO - PROCESS | 9718 | 04:04:34 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41546/tests/dromaeo/cssquery-yui.html) 04:04:34 INFO - PROCESS | 9718 | RSS: Main: 187379712 04:04:34 INFO - PROCESS | 9718 | 04:07:37 INFO - PROCESS | 9718 | Cycle 1(1): loaded http://localhost:41546/tests/dromaeo/cssquery-yui.html (next: http://localhost:41546/tests/dromaeo/cssquery-dojo.html) 04:07:38 INFO - PROCESS | 9718 | RSS: Main: 258117632 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | __start_tp_report 04:07:38 INFO - PROCESS | 9718 | _x_x_mozilla_page_load 04:07:38 INFO - PROCESS | 9718 | _x_x_mozilla_page_load_details 04:07:38 INFO - PROCESS | 9718 | |i|pagename|runs| 04:07:38 INFO - PROCESS | 9718 | |0;dojo.html;2589.5468293048716;2599.2202339298206;2625.054980207125;2630.618560308755;2641.3000554852956;4682.0635872825405;4723.362346083278;4757.310190022445;4781.569658730709;4784.44978827435;4740.739259340735;4757.476677565465;4798.568128868393;4816.084943860664;4819.951800481994;4327.199468098412;4333.155034768221;4401.405810215628;4461.9107617847785;4551.203539380612;4462.642988560908;4496.078303947699;4504.0316331988615;4518.209313370164;4528.139653465847;4328.610425061728;4382.649388048981;4387.605115539617;4400.691951563408;4418.248897687401;4398.186335527937;4405.383246345482;4428.247197976351;4445.53321901199;4516.254817955033;4385.276429389147;4443.777811109431;4450.198964186445;4488.551144885518;4488.865334039984;4785.066911952171;4837.588804951583;4886.14492463821;4898.02039592083;4898.828541001048;4983.252512123175;5093.083245015896;5148.485151484859;5156.664816786897;5216.400113986902;5228.5817134629515;5230.503102205284;5230.607704422146;5234.685918844882;5244.868878278074;4675.742834144086;4821.108095002401;4828.493008234155;4874.19575770001;4886.096072226649;5200.2199670049795;5262.158054711304;5271.077561426735;5315.04329220744;5338.599605029638;5048.5203905628905;5105.974470127626;5108.182690769533;5109.463506331856;5109.9744501277255;5022.271770593316;5184.170532714747;5185.092608793446;5199.974000129975;5201.895962080737;5015;5059.19052951526;5064.49355064498;5086.745662716849;5142.280080788692;5060.620453466005;5060.721660308718;5063.569596584258;5071.492850714899;5125.538701516882;4209.894752631148;4301.075268817157;4310.43964284641;4318.157959197926;4365.432493775852;3708.35103856824;3720.3675375186285;3736.3087828751222;3748.0442487165597;3788.7158463114824;4303.698741088094;4321.762303073361;4324.545922678073;4326.567343265711;4370.890727731832;5060.924086138711;5063.594912407072;5067.341245638044;5069.935313584188;5100.617453690988;4239.173361194537;4314.22343978087;4398.604125628709;4404.691671583022;4455.75493347869;4599.632029437637;4602.424696912886;4616.146012987608;4623.491415944243;4659.324397962277;4811.879703007453;4823.831165909176;4827.179379505422;4830;4872.317875497362;5004.2493625956395;5140.768665409991;5192.896142077062;5193.454687257784;5276.208568714724;4814.927776083432;4865.659403841697;4866.391701037371;4868.17241069012;4887.315775791321;5117.283580298836;5137.306463627512;5138.434772174983;5139.434662187082;5141.974290128525;4970.403551573834;5072.391313042459;5106.080905437057;5127.230915362726;5196.714180719945;5008.148614735431;5012.373453318335;5014.6991180529285;5015.17249653803;5065.696058236517;4997.650164488451;5007.949920500749;5014.899702006012;5016.799328026984;5018.4981501849525;4761.738104404291;4860.659753817199;4900.4854490278;4901.975490122527;4988.551030407136;5079.238114282887;5094.414142373526;5097.617678674188;5100.260462232881;5148.9742551287;4962.156433406258;4972.627052970969;4979.726115063706;4982.551570358685;4984.526469985346 04:07:38 INFO - PROCESS | 9718 | |1;ext.html;553.9500194748052;589.8436914217731;590.9586328956973;592.4519819167275;600.2496878901372;26375;38849.83450496486;40214.99999999996;40301.96980301968;40305.798471007605;39578.8021059895;40158.598414015854;40210.994725131815;40238.19523609534;40250.19499610006;7340.26597340267;7735.767926962186;7924.683012679486;7938.047434307891;10001.299909006373;9839.45882976436;10240.89759102407;10244.385336879774;10245.948770256138;10247.641332553361;10319.071283584513;10373.858875523687;10376.273660843744;10376.561508045603;10376.584936602565;10112.84830727536;10242.53908574116;10252.128569071638;10254.077133058061;10258;9983.550740216635;10042;10049.849252261221;10055.648052318134;10061.547230374577;38120.42819357712;38392.42411363832;38493;38515.807420962716;38533.421998669764;2946.852657367123;3072;3300.5709257796334;3728.45937339087;3817.255635151119;27438.5884211737;29117.12648620545;29716;29763;29916.252093697614;3413.8293085345636;3438.1060924159647;3450.5824468216774;3573.231755172624;3614.042278796121;265712;267687;268683;269103;269679;55359.44640553623;55483.445165547826;55552.44447555473;55892.72053639706;55913.16130257968;58021;58033.419665803645;58067.419325807045;58113.70943145342;58140.41859581435;14706.55880323592;15135.167565783768;15161;15404.306806193748;15406.075635461684;10165.644202453026;11141.610043648596;11437.256578322382;11733.061355091571;11752.647420577396;17811.198496067707;18133.54666133331;18159.45521634353;18163.18265678048;18235.17941692627;6950.30496950311;7107;7125.25184855593;7146.678399471933;7212.3148300911325;742.3986570877606;835.5696816139692;860.6299291304674;880.3221519430003;897.9146981036791;26016.739832601812;26156.215313540622;26205.344866378495;26751.732482675314;27299.590506142424;846.23415808692;861.9310455163571;880.7665968518347;921.2123634292717;1014.457265363042;13794.517191898236;14109.223992680501;14266.572002839725;14493.78259326111;14499.710005799825;404259;409752;410389;411298;411402;50448;51062.23406648903;51108.23337649938;51160.74419627878;51178;1062.585591619269;1102.614085070219;1158.6524042786962;1202.1464760020308;1203.1457665057908;25820.741792581834;29538.55692164662;29608.407831843673;29703;30063.549046764754;41820.58179418167;42365;42368;42428.151436971704;42431.7878410606;297670;299760;299880;299915;300004;22554.097836086374;23016.884915575985;23359.53280934406;24242.757572424754;25082.118841086627;41203.7939810299;41597.168056639304;41946;42010.78994605008;42112;35647;35787.64212357947;35882.282354352246;36236.456453153754;36478.27043459169;35862.820685897444;35929;36505.26989460249;36749.81625091964;36823;35273.29453410866;35950.64049359473;35973.820130900225;36368.81815590905;36403.089922752144;30431.695683042886;32275.838620806746;32929.835350823094;33150.83424582861;33205.335893282485;24883.253502394957;25098.874505627355;25193.748062519142;25275;25454.872725636254 04:07:38 INFO - PROCESS | 9718 | |2;jquery.html;344.40761889549964;365.75859932444575;369.34072680265723;373.74398537002156;381.5821675265585;12142.453589588467;16025.919870400647;16577.668446631076;17058.658826823455;18051;16897.408590699328;17242.482725518224;17473.475795726117;17551.122443877786;17717.822821771777;5006.849794506171;5066.721330326822;5154.742262886841;5190.169572868342;5192.350956130484;3630.6187850275737;5136.794528218867;5138.897222055557;5731.484166425021;6036.034234522477;6043.637381757087;6062.484688801435;6071.392860713938;6072.817815465543;6073.453389194971;5896.616719913213;5995.160677505153;6004.697078258095;6022.3676513966275;6023.6385816851125;5163.122269214205;5269.104252277122;5274.472552744734;5275.657082289639;5278.97360513195;21168.788312116834;21476.24833130833;21638.567228655338;21789.34631961044;21800.236991705213;1989.7114918336904;2318.7565305643;2448.865312407817;2454.8895299711558;2522.167684664075;13347.532836350778;13415.932920335434;13541.729165416637;13576;14203.431862725582;1759.5952930825981;1784.5627821183764;1880.2572983671373;1941.5146213446637;1965.764108306998;71107;71773.64113179453;72100;72624;73548.99999999946;9829.606815727433;9866.309358344846;9930.453825039693;10161.18710503158;10359.63741269067;13849.307534623229;13878.306084695725;13892.097013694078;13896.652583685489;13914.165150091027;7214.711411543584;7346.52247603904;7374.963125184339;7491.138519070268;7587.886181707278;7008.929910700827;7072.469564782662;7196.244394338623;7256.419486441072;7350.705971761176;4176.979115104405;4260.44614200152;4292.613664770186;4438.689291749546;4445.244308467568;1775.9289628414977;1788.5886246163454;1823.1886810369524;1825.3976187857975;1840.162725959685;777.5606782168057;805.923437273458;858.8239410920746;860.238688760439;911.2322095135548;17187.742183867253;17207.655846882994;17846.28614855391;17934.19296131677;18155.273789048555;827.1976183102381;831.4886344897844;839.970601028961;846.9618867150994;858.1761508951477;9423.905760942303;9631.951840240754;9949;10007.699769006942;10008.049235322633;377791;383384;384002;385586;390050;78288.2171178281;79593.60203198947;79834;80301;81180.59409702914;894.186290475677;895.4403497813867;897.5915958238986;911.5578944212052;942.9434233945985;15116.244187790786;15348.232588370314;15700.92149539245;16050.19749012568;16307.918460407622;38826.80586597049;39605;39677.80161099176;39860;40335.59664403318;48327.516724832305;52306.738466307426;52370.476295238084;52970.47029529655;54381.45618543922;5469.015577196143;7330.340269375782;7751.534907905544;7776.533407995538;7921.366290696846;10448.738781530523;11570.76858462843;11845.23006004607;11946.223495472766;12544.87455125437;9372.578233979351;9746.80506389854;9810.460424676712;10053.095221430107;10180.541875615467;9733.707988760349;10202.438865862152;10293.794124117625;10412.427316497666;10733.034026937301;10049.246306526893;10453.634122805664;10479.266451348332;10538.262321637412;10869.456527173452;7492.962535187289;7656.349210317186;7704.730334438268;7713.42149338813;7754.806129846799;8588.785280368042;9743;10060.446675432922;10102.747431314276;10360.637377691744 04:07:38 INFO - PROCESS | 9718 | |3;mootools.html;1270.2632473165565;1361.6459720472678;1407.40185421196;1465.3772146837591;1637.5578593779671;6753.257141714407;7745.1093124290655;8001.879971800413;8037.23646253605;8060.516369017863;7863.80340491488;7906.525608463497;7909.327707144899;7922.088959769614;7924.128345881963;2150.139944022396;2175.4343870593602;2175.651895696689;2181.2583721534675;2183.770704076073;2103.1482249688825;2137.593857167141;2161.502854343502;2161.7189765330486;2162.091921393011;2137.7648458669532;2152.601768672793;2159.773223811501;2162.9351119466364;2181.454636340915;2103.4531021934254;2107.831373490117;2112.155137944819;2112.461322362803;2114.1120729293657;2049.0676742082464;2096.2453516733963;2098.475381154711;2099.2757498662936;2102.831773458135;7916.32711219547;7926.524408535506;7987.201279872024;7987.720429784988;7996.120426753053;4513.255312873372;4660.86017419478;4661.603763680091;4702.576768090854;4711.481737008926;2702.8918843246247;2859.5281778506524;2894.768418526513;2902.521084021134;2903.5499497577907;870.499462808881;870.8388948044632;876.9868451973161;880.7445840706188;888.5468411110315;10720.517576709066;10851.620193293276;10861.13110951122;10863.402512861789;10872.075873550757;3802.16352402471;3968.3253846846105;4082.795860206978;4127.772972486542;4130.215259100762;8485.363597730195;8490.575471226413;8496.66013359471;8499.532525711022;8504.192101750325;4600.700954437951;4603.4015577975315;4605.286180642006;4607.907841843212;4612.838550650711;3723.906902327409;3842.9423558646645;4189.392538081961;4271.765052922057;4279.2083464559155;3335.5997280326515;3393.4909763535666;3488.494168345576;3515.876944306988;3529.9410176946812;990.3711143423834;998.6155330197926;1003.3628645809963;1010.2221289607108;1017.6082208349691;692.6363659078924;728.7340120855849;775.6664634207232;820.6840366458837;841.469873979389;5468.261784659099;5581.685805904876;5668.914966275509;5691.203231547586;5914.792982245601;865.7575878753833;896.2023798819056;903.8192361527589;907.0476000199764;915.9358844880794;3550.6449355064287;3562.686701367701;3573.8927832165077;3595.5146055282203;3750.043738846631;9349.953250233706;9694.75763105914;9785.608575657036;9811.362261452983;9862.852057219148;9562.856557151648;9754.317197796228;9771.267154963263;9797.755056123653;9877.50612469388;7084.787456376317;7203.171635261907;7205.243449437734;7206.963965180141;7207.783766487014;5104.744762761773;5226.71253081069;5264.815731449381;5287.339082614673;5475.041867673221;8933.330000250136;9046.819063618823;9105.180533751995;9113.316501262565;9119.316051296046;9064.546772661472;9216.124468175512;9310.162085412345;9327.766805829908;9359.578818953027;5601.243832082617;6063.151158837854;6223.595466294676;6368.36316368378;6384.106225128578;7465.1788303287685;7731.072271327476;7763.0684317882215;7772.4947878389985;7781.727639532589;6803.7618683345845;6847.109875716125;6869.690863911036;6880.552764070319;7044.119485064367;6615.040819081302;6693.09643198162;6769.221539523018;6944.618046007518;6949.548279361826;6138.785142519991;6212.099245609452;6226.3151053385;6233.189685340877;6235;4513.819447222074;4573.931391029204;4575.65682573815;4701.318308845267;4773.737444440589;4533.478649955297;4535.365048893092;4702.459217190067;4702.623790096715;4899 04:07:38 INFO - PROCESS | 9718 | |4;prototype.html;290.6425097130531;294.4596665119506;303.2797106871181;306.7545963229417;307.4757538397033;4959.6032317414565;4961.776720047597;5002.79988800448;5006.573126658898;5024.497550244973;4591.127685739707;4706.62347012239;4752.976235118819;4784.234522476405;4897;3850.634189751976;3864.381698928172;3883.669888059504;3892.8978351690193;3897.941530877039;3892.4355968384566;3896.454496370496;3901.102746368337;3916.8824935251987;3924.3721004639265;3608.9097772555765;3739.813009349535;3843.404272337778;3868.3810590305557;3884.844606215748;3827.885163445101;3836.635519625631;3842.923141537168;3877.7673339599573;3882.669973052294;3399.6260411354733;3404.438267685829;3413.044347582681;3418.6410426905313;3428.674275943806;4678.883027924295;4744.786484608201;4757.762111894392;4773.856784296477;4802.375691160127;4349.3041113421705;4349.3258544925275;4364.912701745979;4410.16206920684;4421.756803375813;5118.846434606968;5142.151544995071;5149.348085735849;5153.742312884378;5155.793768249266;3685.539307586552;3702.4816525686424;3702.5556933167923;3715.7584756990977;3724.7951362675312;5126.948730512684;5320.334958130234;5325.334333208349;5329.413764485865;5381.623286369956;3811.5045044144085;3817.97647246954;3822.7515211511163;3837.424386342015;3837.6162383761402;3878.864239751595;3881.84472621092;3889.9416508752392;3906.4335671328067;3920.3923391873736;4291.849785257501;4373.781310934441;4380.6057454829215;4421.7789110544345;4426.557344265548;5068.543831055149;5081.59347252219;5089;5094.1849304111165;5115.488451154929;1331.940062697181;1338.8928885689122;1340.9865901341057;1343.4961889291515;1346.8619016930677;830.3191383065948;874.173905659146;896.802768304321;916.6883259691737;930.7393929699695;4452.48796388413;4452.732836029849;4454.844080457168;4457.5988161065015;4461.776911154494;5136.486351364908;5144.742762861842;5161.200013997836;5170.819021334235;5191.247269146028;4291.592298731615;4414.624756895636;4430.253242989303;4449.043455657049;4529.479109902338;2799.5800629905675;2804.6914839367655;2809.845458499802;2810.690824009357;2813.7045610211044;4927.655064145547;4951.925721114186;4953.826616068421;4967.528084831935;4968.726720030433;5182.896342073137;5436.836894893159;5441;5444.537214336747;5467.17992301158;4960.065574425977;4967.503249675004;4968.577670897942;4970.130227210224;4974.283872064132;5336.386315573759;5342.11855043929;5348.866278342917;5362.731863406736;5374.408815030265;5112.542925266282;5159.742012899265;5181.165937206515;5191.610629202901;5197.532222100029;4943.629227808001;4946.703197808143;4982.2811130160035;4990.301357809837;5013.749312534432;3752.212035472581;3756.7039371416818;3777.33896568105;3778.7677800863207;3786.223824116105;5152.922706159336;5296.682199068068;5297.88744363688;5315.2292917525965;5400.540954018952;5108.948910510847;5136.743162841918;5148.330717006766;5163.199704045879;5167.61242906791;5146.214020960542;5158.484151584811;5163.882808048832;5172.741362931763;5191.065608190562;4843.24929635907;4971.254311853251;5008.799648014185;5012.1980483122525;5020.970701006358;4886.242632391985;4972.602191824573;5132.538071573576;5154.716490593053;5160.406553246425;5012.147934851157;5017.422996355466;5051;5075.568576670877;5084.822031229037 04:07:38 INFO - PROCESS | 9718 | |5;yui.html;631.2771876201748;664.007309072936;667.4460198035631;729.51122747759;732.8864026075956;12651.999999999989;13790.517331893374;13810.309484525784;13831.654208644764;13841.930790346047;13305.534306299282;13383.263920484369;13409.798853017188;13427.395767190475;13504;622.3185611755143;751.954882707038;758.7647829172946;776.7980325115483;780.4965797060893;779.7115067425033;781.1212386065677;781.136843787615;781.6990458673423;782.2803021220462;817.627979269431;818.0510607695074;818.7420962396856;818.9426740128193;819.4509678515409;654.6628486329544;785.7839094249127;806.5967016491754;809.1463506001166;810.5866008335732;593.6912805341242;594.0702800117845;594.3610618584995;594.3759052994338;594.4263785447064;13155.07914445989;13190.60428187156;13205.603831884962;13206;13221.669458263621;12706.936465317707;12798.61604151877;12986.155899866479;13164.736705265841;13472.393742281622;11406.54373825046;11409.600663976804;11591.76816463666;11639.35983520906;12036.578719744766;4020.557738648746;4042.6967977401814;4132.462779838632;4140.275451795924;4204.348326009474;59034;60262.096068559;61045;61502.38497615056;61642;9650.324477286664;10001;10031.197504199647;10369.066783989478;10400.895991039994;18050.909745451452;18366.081695915418;18375.356862509747;18377.15154151082;18388.17253223608;6454.774082907075;6488.902666460007;6515.543911926215;6702.530822842355;6771.5259931805285;3597.874074407383;3657.9259193154326;3683.226522430266;3705.444183372516;3707.1844194277214;2825.406664600457;2926.6049083373473;2931.9267018324713;2935.706429357047;2936.6916473769948;537.7042626555457;545.021686073499;545.2747845365733;548.7722595123059;569.3367227180371;528.6642981706567;530.0538538708387;556.8468671115476;572.8457158824971;574.5145352177403;7461.888071678929;7844.764657060297;7861.2531809478005;7972.927038969794;8005.639746211435;504.64674727690766;537.8897326048151;574.1703238819948;608.6500262349078;621.487272999777;3558.893233203008;3654.6162652921594;3663.19409729859;3798.4682144499793;3897.2984862724416;83139.16860831314;86077;87106;88509.55745221362;88879.55560222158;75798.62100689572;75836;76265;77305;78068.60965695135;65563.67218163975;67415.66292168606;67448.66275668591;68974.31025689679;71227.09661292339;7018.067642946411;7210.459215558959;7226.40244356697;7236.927630723836;7407.370383513775;36733.44899826558;36763;38158;42957;43354.13291734211;39874.800625996686;47504;52588.47411525836;53896;62573.37426625858;11746.412679365827;11773.587924422603;11843.111766617365;12234.082443816571;12303.507859685513;12786.168899021533;13015.284159371326;13171.143875648053;13196.340182991004;13247.138935969131;10414.218933580163;10577.206709496664;11296.152788541056;11452.198346116025;11656.592019279284;11654;11841.111916606116;11855.34795586251;12484.500619975099;12488.25070495773;11430.48562814692;11472.254303470248;11939.403029848647;11993.580224692443;12177.147599667938;9428.717138485856;9658.806823863624;9735.70792876215;9749.756256093655;10144.543495542866;8719.69904581574;8983.550822458981;9331.813363732823;9860.30977831545;9914.752131196778 04:07:38 INFO - PROCESS | 9718 | __end_tp_report 04:07:38 INFO - PROCESS | 9718 | __start_cc_report 04:07:38 INFO - PROCESS | 9718 | _x_x_mozilla_cycle_collect,962 04:07:38 INFO - PROCESS | 9718 | __end_cc_report 04:07:38 INFO - PROCESS | 9718 | __startTimestamp1469185658143__endTimestamp 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | ------- Summary: start ------- 04:07:38 INFO - PROCESS | 9718 | Number of tests: 6 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#0] dojo.html Cycles:165 Average:4744.30 Median:4886.10 stddev:516.16 (10.6%) stddev-sans-first:489.28 04:07:38 INFO - PROCESS | 9718 | Values: 2589.5 2599.2 2625.1 2630.6 2641.3 4682.1 4723.4 4757.3 4781.6 4784.4 4740.7 4757.5 4798.6 4816.1 4820.0 4327.2 4333.2 4401.4 4461.9 4551.2 4462.6 4496.1 4504.0 4518.2 4528.1 4328.6 4382.6 4387.6 4400.7 4418.2 4398.2 4405.4 4428.2 4445.5 4516.3 4385.3 4443.8 4450.2 4488.6 4488.9 4785.1 4837.6 4886.1 4898.0 4898.8 4983.3 5093.1 5148.5 5156.7 5216.4 5228.6 5230.5 5230.6 5234.7 5244.9 4675.7 4821.1 4828.5 4874.2 4886.1 5200.2 5262.2 5271.1 5315.0 5338.6 5048.5 5106.0 5108.2 5109.5 5110.0 5022.3 5184.2 5185.1 5200.0 5201.9 5015.0 5059.2 5064.5 5086.7 5142.3 5060.6 5060.7 5063.6 5071.5 5125.5 4209.9 4301.1 4310.4 4318.2 4365.4 3708.4 3720.4 3736.3 3748.0 3788.7 4303.7 4321.8 4324.5 4326.6 4370.9 5060.9 5063.6 5067.3 5069.9 5100.6 4239.2 4314.2 4398.6 4404.7 4455.8 4599.6 4602.4 4616.1 4623.5 4659.3 4811.9 4823.8 4827.2 4830.0 4872.3 5004.2 5140.8 5192.9 5193.5 5276.2 4814.9 4865.7 4866.4 4868.2 4887.3 5117.3 5137.3 5138.4 5139.4 5142.0 4970.4 5072.4 5106.1 5127.2 5196.7 5008.1 5012.4 5014.7 5015.2 5065.7 4997.7 5007.9 5014.9 5016.8 5018.5 4761.7 4860.7 4900.5 4902.0 4988.6 5079.2 5094.4 5097.6 5100.3 5149.0 4962.2 4972.6 4979.7 4982.6 4984.5 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#1] ext.html Cycles:180 Average:48358.82 Median:3444.34 stddev:87186.45 (2531.3%) stddev-sans-first:87357.15 04:07:38 INFO - PROCESS | 9718 | Values: 554.0 589.8 591.0 592.5 600.2 26375.0 38849.8 40215.0 40302.0 40305.8 39578.8 40158.6 40211.0 40238.2 40250.2 7340.3 7735.8 7924.7 7938.0 10001.3 9839.5 10240.9 10244.4 10245.9 10247.6 10319.1 10373.9 10376.3 10376.6 10376.6 10112.8 10242.5 10252.1 10254.1 10258.0 9983.6 10042.0 10049.8 10055.6 10061.5 38120.4 38392.4 38493.0 38515.8 38533.4 2946.9 3072.0 3300.6 3728.5 3817.3 27438.6 29117.1 29716.0 29763.0 29916.3 3413.8 3438.1 3450.6 3573.2 3614.0 265712.0 267687.0 268683.0 269103.0 269679.0 55359.4 55483.4 55552.4 55892.7 55913.2 58021.0 58033.4 58067.4 58113.7 58140.4 14706.6 15135.2 15161.0 15404.3 15406.1 10165.6 11141.6 11437.3 11733.1 11752.6 17811.2 18133.5 18159.5 18163.2 18235.2 6950.3 7107.0 7125.3 7146.7 7212.3 742.4 835.6 860.6 880.3 897.9 26016.7 26156.2 26205.3 26751.7 27299.6 846.2 861.9 880.8 921.2 1014.5 13794.5 14109.2 14266.6 14493.8 14499.7 404259.0 409752.0 410389.0 411298.0 411402.0 50448.0 51062.2 51108.2 51160.7 51178.0 1062.6 1102.6 1158.7 1202.1 1203.1 25820.7 29538.6 29608.4 29703.0 30063.5 41820.6 42365.0 42368.0 42428.2 42431.8 297670.0 299760.0 299880.0 299915.0 300004.0 22554.1 23016.9 23359.5 24242.8 25082.1 41203.8 41597.2 41946.0 42010.8 42112.0 35647.0 35787.6 35882.3 36236.5 36478.3 35862.8 35929.0 36505.3 36749.8 36823.0 35273.3 35950.6 35973.8 36368.8 36403.1 30431.7 32275.8 32929.8 33150.8 33205.3 24883.3 25098.9 25193.7 25275.0 25454.9 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#2] jquery.html Cycles:180 Average:24610.70 Median:4276.53 stddev:63594.58 (1487.1%) stddev-sans-first:63746.88 04:07:38 INFO - PROCESS | 9718 | Values: 344.4 365.8 369.3 373.7 381.6 12142.5 16025.9 16577.7 17058.7 18051.0 16897.4 17242.5 17473.5 17551.1 17717.8 5006.8 5066.7 5154.7 5190.2 5192.4 3630.6 5136.8 5138.9 5731.5 6036.0 6043.6 6062.5 6071.4 6072.8 6073.5 5896.6 5995.2 6004.7 6022.4 6023.6 5163.1 5269.1 5274.5 5275.7 5279.0 21168.8 21476.2 21638.6 21789.3 21800.2 1989.7 2318.8 2448.9 2454.9 2522.2 13347.5 13415.9 13541.7 13576.0 14203.4 1759.6 1784.6 1880.3 1941.5 1965.8 71107.0 71773.6 72100.0 72624.0 73549.0 9829.6 9866.3 9930.5 10161.2 10359.6 13849.3 13878.3 13892.1 13896.7 13914.2 7214.7 7346.5 7375.0 7491.1 7587.9 7008.9 7072.5 7196.2 7256.4 7350.7 4177.0 4260.4 4292.6 4438.7 4445.2 1775.9 1788.6 1823.2 1825.4 1840.2 777.6 805.9 858.8 860.2 911.2 17187.7 17207.7 17846.3 17934.2 18155.3 827.2 831.5 840.0 847.0 858.2 9423.9 9632.0 9949.0 10007.7 10008.0 377791.0 383384.0 384002.0 385586.0 390050.0 78288.2 79593.6 79834.0 80301.0 81180.6 894.2 895.4 897.6 911.6 942.9 15116.2 15348.2 15700.9 16050.2 16307.9 38826.8 39605.0 39677.8 39860.0 40335.6 48327.5 52306.7 52370.5 52970.5 54381.5 5469.0 7330.3 7751.5 7776.5 7921.4 10448.7 11570.8 11845.2 11946.2 12544.9 9372.6 9746.8 9810.5 10053.1 10180.5 9733.7 10202.4 10293.8 10412.4 10733.0 10049.2 10453.6 10479.3 10538.3 10869.5 7493.0 7656.3 7704.7 7713.4 7754.8 8588.8 9743.0 10060.4 10102.7 10360.6 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#3] mootools.html Cycles:180 Average:5131.69 Median:5635.08 stddev:2918.98 (51.8%) stddev-sans-first:2912.74 04:07:38 INFO - PROCESS | 9718 | Values: 1270.3 1361.6 1407.4 1465.4 1637.6 6753.3 7745.1 8001.9 8037.2 8060.5 7863.8 7906.5 7909.3 7922.1 7924.1 2150.1 2175.4 2175.7 2181.3 2183.8 2103.1 2137.6 2161.5 2161.7 2162.1 2137.8 2152.6 2159.8 2162.9 2181.5 2103.5 2107.8 2112.2 2112.5 2114.1 2049.1 2096.2 2098.5 2099.3 2102.8 7916.3 7926.5 7987.2 7987.7 7996.1 4513.3 4660.9 4661.6 4702.6 4711.5 2702.9 2859.5 2894.8 2902.5 2903.5 870.5 870.8 877.0 880.7 888.5 10720.5 10851.6 10861.1 10863.4 10872.1 3802.2 3968.3 4082.8 4127.8 4130.2 8485.4 8490.6 8496.7 8499.5 8504.2 4600.7 4603.4 4605.3 4607.9 4612.8 3723.9 3842.9 4189.4 4271.8 4279.2 3335.6 3393.5 3488.5 3515.9 3529.9 990.4 998.6 1003.4 1010.2 1017.6 692.6 728.7 775.7 820.7 841.5 5468.3 5581.7 5668.9 5691.2 5914.8 865.8 896.2 903.8 907.0 915.9 3550.6 3562.7 3573.9 3595.5 3750.0 9350.0 9694.8 9785.6 9811.4 9862.9 9562.9 9754.3 9771.3 9797.8 9877.5 7084.8 7203.2 7205.2 7207.0 7207.8 5104.7 5226.7 5264.8 5287.3 5475.0 8933.3 9046.8 9105.2 9113.3 9119.3 9064.5 9216.1 9310.2 9327.8 9359.6 5601.2 6063.2 6223.6 6368.4 6384.1 7465.2 7731.1 7763.1 7772.5 7781.7 6803.8 6847.1 6869.7 6880.6 7044.1 6615.0 6693.1 6769.2 6944.6 6949.5 6138.8 6212.1 6226.3 6233.2 6235.0 4513.8 4573.9 4575.7 4701.3 4773.7 4533.5 4535.4 4702.5 4702.6 4899.0 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#4] prototype.html Cycles:180 Average:4269.19 Median:4891.62 stddev:1224.70 (25.0%) stddev-sans-first:1191.17 04:07:38 INFO - PROCESS | 9718 | Values: 290.6 294.5 303.3 306.8 307.5 4959.6 4961.8 5002.8 5006.6 5024.5 4591.1 4706.6 4753.0 4784.2 4897.0 3850.6 3864.4 3883.7 3892.9 3897.9 3892.4 3896.5 3901.1 3916.9 3924.4 3608.9 3739.8 3843.4 3868.4 3884.8 3827.9 3836.6 3842.9 3877.8 3882.7 3399.6 3404.4 3413.0 3418.6 3428.7 4678.9 4744.8 4757.8 4773.9 4802.4 4349.3 4349.3 4364.9 4410.2 4421.8 5118.8 5142.2 5149.3 5153.7 5155.8 3685.5 3702.5 3702.6 3715.8 3724.8 5126.9 5320.3 5325.3 5329.4 5381.6 3811.5 3818.0 3822.8 3837.4 3837.6 3878.9 3881.8 3889.9 3906.4 3920.4 4291.8 4373.8 4380.6 4421.8 4426.6 5068.5 5081.6 5089.0 5094.2 5115.5 1331.9 1338.9 1341.0 1343.5 1346.9 830.3 874.2 896.8 916.7 930.7 4452.5 4452.7 4454.8 4457.6 4461.8 5136.5 5144.7 5161.2 5170.8 5191.2 4291.6 4414.6 4430.3 4449.0 4529.5 2799.6 2804.7 2809.8 2810.7 2813.7 4927.7 4951.9 4953.8 4967.5 4968.7 5182.9 5436.8 5441.0 5444.5 5467.2 4960.1 4967.5 4968.6 4970.1 4974.3 5336.4 5342.1 5348.9 5362.7 5374.4 5112.5 5159.7 5181.2 5191.6 5197.5 4943.6 4946.7 4982.3 4990.3 5013.7 3752.2 3756.7 3777.3 3778.8 3786.2 5152.9 5296.7 5297.9 5315.2 5400.5 5108.9 5136.7 5148.3 5163.2 5167.6 5146.2 5158.5 5163.9 5172.7 5191.1 4843.2 4971.3 5008.8 5012.2 5021.0 4886.2 4972.6 5132.5 5154.7 5160.4 5012.1 5017.4 5051.0 5075.6 5084.8 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - PROCESS | 9718 | [#5] yui.html Cycles:180 Average:16598.58 Median:533.88 stddev:22719.66 (4255.6%) stddev-sans-first:22751.76 04:07:38 INFO - PROCESS | 9718 | Values: 631.3 664.0 667.4 729.5 732.9 12652.0 13790.5 13810.3 13831.7 13841.9 13305.5 13383.3 13409.8 13427.4 13504.0 622.3 752.0 758.8 776.8 780.5 779.7 781.1 781.1 781.7 782.3 817.6 818.1 818.7 818.9 819.5 654.7 785.8 806.6 809.1 810.6 593.7 594.1 594.4 594.4 594.4 13155.1 13190.6 13205.6 13206.0 13221.7 12706.9 12798.6 12986.2 13164.7 13472.4 11406.5 11409.6 11591.8 11639.4 12036.6 4020.6 4042.7 4132.5 4140.3 4204.3 59034.0 60262.1 61045.0 61502.4 61642.0 9650.3 10001.0 10031.2 10369.1 10400.9 18050.9 18366.1 18375.4 18377.2 18388.2 6454.8 6488.9 6515.5 6702.5 6771.5 3597.9 3657.9 3683.2 3705.4 3707.2 2825.4 2926.6 2931.9 2935.7 2936.7 537.7 545.0 545.3 548.8 569.3 528.7 530.1 556.8 572.8 574.5 7461.9 7844.8 7861.3 7972.9 8005.6 504.6 537.9 574.2 608.7 621.5 3558.9 3654.6 3663.2 3798.5 3897.3 83139.2 86077.0 87106.0 88509.6 88879.6 75798.6 75836.0 76265.0 77305.0 78068.6 65563.7 67415.7 67448.7 68974.3 71227.1 7018.1 7210.5 7226.4 7236.9 7407.4 36733.4 36763.0 38158.0 42957.0 43354.1 39874.8 47504.0 52588.5 53896.0 62573.4 11746.4 11773.6 11843.1 12234.1 12303.5 12786.2 13015.3 13171.1 13196.3 13247.1 10414.2 10577.2 11296.2 11452.2 11656.6 11654.0 11841.1 11855.3 12484.5 12488.3 11430.5 11472.3 11939.4 11993.6 12177.1 9428.7 9658.8 9735.7 9749.8 10144.5 8719.7 8983.6 9331.8 9860.3 9914.8 04:07:38 INFO - PROCESS | 9718 | -------- Summary: end -------- 04:07:38 INFO - PROCESS | 9718 | 04:07:38 INFO - TEST-INFO | 9718: exit 0 04:07:38 INFO - TEST-OK | dromaeo_css | took 1120838ms 04:07:38 INFO - TEST-START | kraken 04:07:38 INFO - operating with platform_type : linux_ 04:07:38 INFO - Initialising browser for kraken test... 04:07:38 INFO - TEST-INFO | started process 9904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZgcZxU/profile http://localhost:41546/getInfo.html) 04:07:42 INFO - PROCESS | 9904 | JavaScript error: , line 0: uncaught exception: undefined 04:07:43 INFO - PROCESS | 9904 | __metrics Screen width/height:1600/1200 04:07:43 INFO - PROCESS | 9904 | colorDepth:24 04:07:43 INFO - PROCESS | 9904 | Browser inner width/height: 1024/697 04:07:43 INFO - PROCESS | 9904 | __metrics 04:07:44 INFO - PROCESS | 9904 | 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" 04:07:44 INFO - PROCESS | 9904 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:07:45 INFO - PROCESS | 9904 | console.error: PushService: 04:07:45 INFO - PROCESS | 9904 | stateChangeProcessEnqueue: Error transitioning state 04:07:45 INFO - PROCESS | 9904 | UnknownError 04:07:45 INFO - TEST-INFO | 9904: exit 0 04:07:45 INFO - Browser initialized. 04:07:45 INFO - Running cycle 1/1 for kraken test... 04:07:45 INFO - TEST-INFO | started process 9987 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZgcZxU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:07:57 INFO - PROCESS | 9987 | RSS: Main: 159252480 04:07:57 INFO - PROCESS | 9987 | 04:08:00 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41546/tests/kraken/driver.html?testName=audio-beat-detection) 04:08:01 INFO - PROCESS | 9987 | RSS: Main: 215818240 04:08:01 INFO - PROCESS | 9987 | 04:08:04 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41546/tests/kraken/driver.html?testName=audio-dft) 04:08:04 INFO - PROCESS | 9987 | RSS: Main: 193347584 04:08:04 INFO - PROCESS | 9987 | 04:08:07 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41546/tests/kraken/driver.html?testName=audio-fft) 04:08:08 INFO - PROCESS | 9987 | RSS: Main: 273821696 04:08:08 INFO - PROCESS | 9987 | 04:08:10 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41546/tests/kraken/driver.html?testName=audio-oscillator) 04:08:11 INFO - PROCESS | 9987 | RSS: Main: 182628352 04:08:11 INFO - PROCESS | 9987 | 04:08:13 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:08:14 INFO - PROCESS | 9987 | RSS: Main: 186593280 04:08:14 INFO - PROCESS | 9987 | 04:08:17 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41546/tests/kraken/driver.html?testName=imaging-darkroom) 04:08:17 INFO - PROCESS | 9987 | RSS: Main: 223612928 04:08:17 INFO - PROCESS | 9987 | 04:08:21 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41546/tests/kraken/driver.html?testName=imaging-desaturate) 04:08:22 INFO - PROCESS | 9987 | RSS: Main: 222302208 04:08:22 INFO - PROCESS | 9987 | 04:08:24 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41546/tests/kraken/driver.html?testName=json-parse-financial) 04:08:25 INFO - PROCESS | 9987 | RSS: Main: 222756864 04:08:25 INFO - PROCESS | 9987 | 04:08:27 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:08:27 INFO - PROCESS | 9987 | RSS: Main: 186880000 04:08:27 INFO - PROCESS | 9987 | 04:08:29 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:08:30 INFO - PROCESS | 9987 | RSS: Main: 192991232 04:08:30 INFO - PROCESS | 9987 | 04:08:32 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:08:33 INFO - PROCESS | 9987 | RSS: Main: 196022272 04:08:33 INFO - PROCESS | 9987 | 04:08:36 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:08:36 INFO - PROCESS | 9987 | RSS: Main: 190783488 04:08:36 INFO - PROCESS | 9987 | 04:08:39 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:08:40 INFO - PROCESS | 9987 | RSS: Main: 195051520 04:08:40 INFO - PROCESS | 9987 | 04:08:41 INFO - PROCESS | 9987 | Cycle 1(1): loaded http://localhost:41546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41546/tests/kraken/driver.html?testName=ai-astar) 04:08:42 INFO - PROCESS | 9987 | RSS: Main: 246415360 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | __start_tp_report 04:08:42 INFO - PROCESS | 9987 | _x_x_mozilla_page_load 04:08:42 INFO - PROCESS | 9987 | _x_x_mozilla_page_load_details 04:08:42 INFO - PROCESS | 9987 | |i|pagename|runs| 04:08:42 INFO - PROCESS | 9987 | |0;ai-astar;107;116;115;114;115;115;114;113;114;115 04:08:42 INFO - PROCESS | 9987 | |1;audio-beat-detection;149;124;130;186;125;126;156;127;165;122 04:08:42 INFO - PROCESS | 9987 | |2;audio-dft;161;160;162;162;162;162;161;161;161;161 04:08:42 INFO - PROCESS | 9987 | |3;audio-fft;78;76;76;131;79;76;75;76;99;75 04:08:42 INFO - PROCESS | 9987 | |4;audio-oscillator;86;85;117;85;86;86;114;86;86;120 04:08:42 INFO - PROCESS | 9987 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 04:08:42 INFO - PROCESS | 9987 | |6;imaging-darkroom;146;146;146;146;146;146;148;147;147;147 04:08:42 INFO - PROCESS | 9987 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 04:08:42 INFO - PROCESS | 9987 | |8;json-parse-financial;63;61;62;61;62;62;63;62;62;61 04:08:42 INFO - PROCESS | 9987 | |9;json-stringify-tinderbox;67;66;66;66;66;66;67;66;69;70 04:08:42 INFO - PROCESS | 9987 | |10;stanford-crypto-aes;80;89;80;79;89;79;79;79;79;78 04:08:42 INFO - PROCESS | 9987 | |11;stanford-crypto-ccm;133;128;129;141;128;127;129;132;223;131 04:08:42 INFO - PROCESS | 9987 | |12;stanford-crypto-pbkdf2;163;162;162;161;163;162;161;167;164;165 04:08:42 INFO - PROCESS | 9987 | |13;stanford-crypto-sha256-iterative;69;72;67;67;66;66;66;66;65;66 04:08:42 INFO - PROCESS | 9987 | __end_tp_report 04:08:42 INFO - PROCESS | 9987 | __start_cc_report 04:08:42 INFO - PROCESS | 9987 | _x_x_mozilla_cycle_collect,1952 04:08:42 INFO - PROCESS | 9987 | __end_cc_report 04:08:42 INFO - PROCESS | 9987 | __startTimestamp1469185722210__endTimestamp 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | ------- Summary: start ------- 04:08:42 INFO - PROCESS | 9987 | Number of tests: 14 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:2.53 (2.2%) stddev-sans-first:0.88 04:08:42 INFO - PROCESS | 9987 | Values: 107.0 116.0 115.0 114.0 115.0 115.0 114.0 113.0 114.0 115.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:139.50 stddev:21.95 (15.7%) stddev-sans-first:23.09 04:08:42 INFO - PROCESS | 9987 | Values: 149.0 124.0 130.0 186.0 125.0 126.0 156.0 127.0 165.0 122.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 04:08:42 INFO - PROCESS | 9987 | Values: 161.0 160.0 162.0 162.0 162.0 162.0 161.0 161.0 161.0 161.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#3] audio-fft Cycles:10 Average:84.10 Median:76.00 stddev:17.99 (23.7%) stddev-sans-first:18.95 04:08:42 INFO - PROCESS | 9987 | Values: 78.0 76.0 76.0 131.0 79.0 76.0 75.0 76.0 99.0 75.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:86.00 stddev:15.18 (17.7%) stddev-sans-first:15.74 04:08:42 INFO - PROCESS | 9987 | Values: 86.0 85.0 117.0 85.0 86.0 86.0 114.0 86.0 86.0 120.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 04:08:42 INFO - PROCESS | 9987 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 04:08:42 INFO - PROCESS | 9987 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 148.0 147.0 147.0 147.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:08:42 INFO - PROCESS | 9987 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 04:08:42 INFO - PROCESS | 9987 | Values: 63.0 61.0 62.0 61.0 62.0 62.0 63.0 62.0 62.0 61.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:66.50 stddev:1.45 (2.2%) stddev-sans-first:1.54 04:08:42 INFO - PROCESS | 9987 | Values: 67.0 66.0 66.0 66.0 66.0 66.0 67.0 66.0 69.0 70.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:79.50 stddev:4.20 (5.3%) stddev-sans-first:4.44 04:08:42 INFO - PROCESS | 9987 | Values: 80.0 89.0 80.0 79.0 89.0 79.0 79.0 79.0 79.0 78.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:131.50 stddev:29.41 (22.4%) stddev-sans-first:31.08 04:08:42 INFO - PROCESS | 9987 | Values: 133.0 128.0 129.0 141.0 128.0 127.0 129.0 132.0 223.0 131.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.00 stddev:1.89 (1.2%) stddev-sans-first:2.00 04:08:42 INFO - PROCESS | 9987 | Values: 163.0 162.0 162.0 161.0 163.0 162.0 161.0 167.0 164.0 165.0 04:08:42 INFO - PROCESS | 9987 | 04:08:42 INFO - PROCESS | 9987 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.50 stddev:2.05 (3.1%) stddev-sans-first:2.05 04:08:42 INFO - PROCESS | 9987 | Values: 69.0 72.0 67.0 67.0 66.0 66.0 66.0 66.0 65.0 66.0 04:08:42 INFO - PROCESS | 9987 | -------- Summary: end -------- 04:08:42 INFO - PROCESS | 9987 | 04:08:43 INFO - TEST-INFO | 9987: exit 0 04:08:43 INFO - TEST-OK | kraken | took 64331ms 04:08:43 INFO - SUITE-END | took 1185s 04:08:43 INFO - Completed test suite (00:19:45) 04:08:43 INFO - javascript benchmark 04:08:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2589.5468293048716, 2599.2202339298206, 2625.054980207125, 2630.618560308755, 2641.3000554852956, 4682.0635872825405, 4723.362346083278, 4757.310190022445, 4781.569658730709, 4784.44978827435, 4740.739259340735, 4757.476677565465, 4798.568128868393, 4816.084943860664, 4819.951800481994, 4327.199468098412, 4333.155034768221, 4401.405810215628, 4461.9107617847785, 4551.203539380612, 4462.642988560908, 4496.078303947699, 4504.0316331988615, 4518.209313370164, 4528.139653465847, 4328.610425061728, 4382.649388048981, 4387.605115539617, 4400.691951563408, 4418.248897687401, 4398.186335527937, 4405.383246345482, 4428.247197976351, 4445.53321901199, 4516.254817955033, 4385.276429389147, 4443.777811109431, 4450.198964186445, 4488.551144885518, 4488.865334039984, 4785.066911952171, 4837.588804951583, 4886.14492463821, 4898.02039592083, 4898.828541001048, 4983.252512123175, 5093.083245015896, 5148.485151484859, 5156.664816786897, 5216.400113986902, 5228.5817134629515, 5230.503102205284, 5230.607704422146, 5234.685918844882, 5244.868878278074, 4675.742834144086, 4821.108095002401, 4828.493008234155, 4874.19575770001, 4886.096072226649, 5200.2199670049795, 5262.158054711304, 5271.077561426735, 5315.04329220744, 5338.599605029638, 5048.5203905628905, 5105.974470127626, 5108.182690769533, 5109.463506331856, 5109.9744501277255, 5022.271770593316, 5184.170532714747, 5185.092608793446, 5199.974000129975, 5201.895962080737, 5015.0, 5059.19052951526, 5064.49355064498, 5086.745662716849, 5142.280080788692, 5060.620453466005, 5060.721660308718, 5063.569596584258, 5071.492850714899, 5125.538701516882, 4209.894752631148, 4301.075268817157, 4310.43964284641, 4318.157959197926, 4365.432493775852, 3708.35103856824, 3720.3675375186285, 3736.3087828751222, 3748.0442487165597, 3788.7158463114824, 4303.698741088094, 4321.762303073361, 4324.545922678073, 4326.567343265711, 4370.890727731832, 5060.924086138711, 5063.594912407072, 5067.341245638044, 5069.935313584188, 5100.617453690988, 4239.173361194537, 4314.22343978087, 4398.604125628709, 4404.691671583022, 4455.75493347869, 4599.632029437637, 4602.424696912886, 4616.146012987608, 4623.491415944243, 4659.324397962277, 4811.879703007453, 4823.831165909176, 4827.179379505422, 4830.0, 4872.317875497362, 5004.2493625956395, 5140.768665409991, 5192.896142077062, 5193.454687257784, 5276.208568714724, 4814.927776083432, 4865.659403841697, 4866.391701037371, 4868.17241069012, 4887.315775791321, 5117.283580298836, 5137.306463627512, 5138.434772174983, 5139.434662187082, 5141.974290128525, 4970.403551573834, 5072.391313042459, 5106.080905437057, 5127.230915362726, 5196.714180719945, 5008.148614735431, 5012.373453318335, 5014.6991180529285, 5015.17249653803, 5065.696058236517, 4997.650164488451, 5007.949920500749, 5014.899702006012, 5016.799328026984, 5018.4981501849525, 4761.738104404291, 4860.659753817199, 4900.4854490278, 4901.975490122527, 4988.551030407136, 5079.238114282887, 5094.414142373526, 5097.617678674188, 5100.260462232881, 5148.9742551287, 4962.156433406258, 4972.627052970969, 4979.726115063706, 4982.551570358685, 4984.526469985346], "value": 4709.775822244596, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.9500194748052, 589.8436914217731, 590.9586328956973, 592.4519819167275, 600.2496878901372, 26375.0, 38849.83450496486, 40214.99999999996, 40301.96980301968, 40305.798471007605, 39578.8021059895, 40158.598414015854, 40210.994725131815, 40238.19523609534, 40250.19499610006, 7340.26597340267, 7735.767926962186, 7924.683012679486, 7938.047434307891, 10001.299909006373, 9839.45882976436, 10240.89759102407, 10244.385336879774, 10245.948770256138, 10247.641332553361, 10319.071283584513, 10373.858875523687, 10376.273660843744, 10376.561508045603, 10376.584936602565, 10112.84830727536, 10242.53908574116, 10252.128569071638, 10254.077133058061, 10258.0, 9983.550740216635, 10042.0, 10049.849252261221, 10055.648052318134, 10061.547230374577, 38120.42819357712, 38392.42411363832, 38493.0, 38515.807420962716, 38533.421998669764, 2946.852657367123, 3072.0, 3300.5709257796334, 3728.45937339087, 3817.255635151119, 27438.5884211737, 29117.12648620545, 29716.0, 29763.0, 29916.252093697614, 3413.8293085345636, 3438.1060924159647, 3450.5824468216774, 3573.231755172624, 3614.042278796121, 265712.0, 267687.0, 268683.0, 269103.0, 269679.0, 55359.44640553623, 55483.445165547826, 55552.44447555473, 55892.72053639706, 55913.16130257968, 58021.0, 58033.419665803645, 58067.419325807045, 58113.70943145342, 58140.41859581435, 14706.55880323592, 15135.167565783768, 15161.0, 15404.306806193748, 15406.075635461684, 10165.644202453026, 11141.610043648596, 11437.256578322382, 11733.061355091571, 11752.647420577396, 17811.198496067707, 18133.54666133331, 18159.45521634353, 18163.18265678048, 18235.17941692627, 6950.30496950311, 7107.0, 7125.25184855593, 7146.678399471933, 7212.3148300911325, 742.3986570877606, 835.5696816139692, 860.6299291304674, 880.3221519430003, 897.9146981036791, 26016.739832601812, 26156.215313540622, 26205.344866378495, 26751.732482675314, 27299.590506142424, 846.23415808692, 861.9310455163571, 880.7665968518347, 921.2123634292717, 1014.457265363042, 13794.517191898236, 14109.223992680501, 14266.572002839725, 14493.78259326111, 14499.710005799825, 404259.0, 409752.0, 410389.0, 411298.0, 411402.0, 50448.0, 51062.23406648903, 51108.23337649938, 51160.74419627878, 51178.0, 1062.585591619269, 1102.614085070219, 1158.6524042786962, 1202.1464760020308, 1203.1457665057908, 25820.741792581834, 29538.55692164662, 29608.407831843673, 29703.0, 30063.549046764754, 41820.58179418167, 42365.0, 42368.0, 42428.151436971704, 42431.7878410606, 297670.0, 299760.0, 299880.0, 299915.0, 300004.0, 22554.097836086374, 23016.884915575985, 23359.53280934406, 24242.757572424754, 25082.118841086627, 41203.7939810299, 41597.168056639304, 41946.0, 42010.78994605008, 42112.0, 35647.0, 35787.64212357947, 35882.282354352246, 36236.456453153754, 36478.27043459169, 35862.820685897444, 35929.0, 36505.26989460249, 36749.81625091964, 36823.0, 35273.29453410866, 35950.64049359473, 35973.820130900225, 36368.81815590905, 36403.089922752144, 30431.695683042886, 32275.838620806746, 32929.835350823094, 33150.83424582861, 33205.335893282485, 24883.253502394957, 25098.874505627355, 25193.748062519142, 25275.0, 25454.872725636254], "value": 18095.42837645911, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.40761889549964, 365.75859932444575, 369.34072680265723, 373.74398537002156, 381.5821675265585, 12142.453589588467, 16025.919870400647, 16577.668446631076, 17058.658826823455, 18051.0, 16897.408590699328, 17242.482725518224, 17473.475795726117, 17551.122443877786, 17717.822821771777, 5006.849794506171, 5066.721330326822, 5154.742262886841, 5190.169572868342, 5192.350956130484, 3630.6187850275737, 5136.794528218867, 5138.897222055557, 5731.484166425021, 6036.034234522477, 6043.637381757087, 6062.484688801435, 6071.392860713938, 6072.817815465543, 6073.453389194971, 5896.616719913213, 5995.160677505153, 6004.697078258095, 6022.3676513966275, 6023.6385816851125, 5163.122269214205, 5269.104252277122, 5274.472552744734, 5275.657082289639, 5278.97360513195, 21168.788312116834, 21476.24833130833, 21638.567228655338, 21789.34631961044, 21800.236991705213, 1989.7114918336904, 2318.7565305643, 2448.865312407817, 2454.8895299711558, 2522.167684664075, 13347.532836350778, 13415.932920335434, 13541.729165416637, 13576.0, 14203.431862725582, 1759.5952930825981, 1784.5627821183764, 1880.2572983671373, 1941.5146213446637, 1965.764108306998, 71107.0, 71773.64113179453, 72100.0, 72624.0, 73548.99999999946, 9829.606815727433, 9866.309358344846, 9930.453825039693, 10161.18710503158, 10359.63741269067, 13849.307534623229, 13878.306084695725, 13892.097013694078, 13896.652583685489, 13914.165150091027, 7214.711411543584, 7346.52247603904, 7374.963125184339, 7491.138519070268, 7587.886181707278, 7008.929910700827, 7072.469564782662, 7196.244394338623, 7256.419486441072, 7350.705971761176, 4176.979115104405, 4260.44614200152, 4292.613664770186, 4438.689291749546, 4445.244308467568, 1775.9289628414977, 1788.5886246163454, 1823.1886810369524, 1825.3976187857975, 1840.162725959685, 777.5606782168057, 805.923437273458, 858.8239410920746, 860.238688760439, 911.2322095135548, 17187.742183867253, 17207.655846882994, 17846.28614855391, 17934.19296131677, 18155.273789048555, 827.1976183102381, 831.4886344897844, 839.970601028961, 846.9618867150994, 858.1761508951477, 9423.905760942303, 9631.951840240754, 9949.0, 10007.699769006942, 10008.049235322633, 377791.0, 383384.0, 384002.0, 385586.0, 390050.0, 78288.2171178281, 79593.60203198947, 79834.0, 80301.0, 81180.59409702914, 894.186290475677, 895.4403497813867, 897.5915958238986, 911.5578944212052, 942.9434233945985, 15116.244187790786, 15348.232588370314, 15700.92149539245, 16050.19749012568, 16307.918460407622, 38826.80586597049, 39605.0, 39677.80161099176, 39860.0, 40335.59664403318, 48327.516724832305, 52306.738466307426, 52370.476295238084, 52970.47029529655, 54381.45618543922, 5469.015577196143, 7330.340269375782, 7751.534907905544, 7776.533407995538, 7921.366290696846, 10448.738781530523, 11570.76858462843, 11845.23006004607, 11946.223495472766, 12544.87455125437, 9372.578233979351, 9746.80506389854, 9810.460424676712, 10053.095221430107, 10180.541875615467, 9733.707988760349, 10202.438865862152, 10293.794124117625, 10412.427316497666, 10733.034026937301, 10049.246306526893, 10453.634122805664, 10479.266451348332, 10538.262321637412, 10869.456527173452, 7492.962535187289, 7656.349210317186, 7704.730334438268, 7713.42149338813, 7754.806129846799, 8588.785280368042, 9743.0, 10060.446675432922, 10102.747431314276, 10360.637377691744], "value": 8421.300700334392, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.2632473165565, 1361.6459720472678, 1407.40185421196, 1465.3772146837591, 1637.5578593779671, 6753.257141714407, 7745.1093124290655, 8001.879971800413, 8037.23646253605, 8060.516369017863, 7863.80340491488, 7906.525608463497, 7909.327707144899, 7922.088959769614, 7924.128345881963, 2150.139944022396, 2175.4343870593602, 2175.651895696689, 2181.2583721534675, 2183.770704076073, 2103.1482249688825, 2137.593857167141, 2161.502854343502, 2161.7189765330486, 2162.091921393011, 2137.7648458669532, 2152.601768672793, 2159.773223811501, 2162.9351119466364, 2181.454636340915, 2103.4531021934254, 2107.831373490117, 2112.155137944819, 2112.461322362803, 2114.1120729293657, 2049.0676742082464, 2096.2453516733963, 2098.475381154711, 2099.2757498662936, 2102.831773458135, 7916.32711219547, 7926.524408535506, 7987.201279872024, 7987.720429784988, 7996.120426753053, 4513.255312873372, 4660.86017419478, 4661.603763680091, 4702.576768090854, 4711.481737008926, 2702.8918843246247, 2859.5281778506524, 2894.768418526513, 2902.521084021134, 2903.5499497577907, 870.499462808881, 870.8388948044632, 876.9868451973161, 880.7445840706188, 888.5468411110315, 10720.517576709066, 10851.620193293276, 10861.13110951122, 10863.402512861789, 10872.075873550757, 3802.16352402471, 3968.3253846846105, 4082.795860206978, 4127.772972486542, 4130.215259100762, 8485.363597730195, 8490.575471226413, 8496.66013359471, 8499.532525711022, 8504.192101750325, 4600.700954437951, 4603.4015577975315, 4605.286180642006, 4607.907841843212, 4612.838550650711, 3723.906902327409, 3842.9423558646645, 4189.392538081961, 4271.765052922057, 4279.2083464559155, 3335.5997280326515, 3393.4909763535666, 3488.494168345576, 3515.876944306988, 3529.9410176946812, 990.3711143423834, 998.6155330197926, 1003.3628645809963, 1010.2221289607108, 1017.6082208349691, 692.6363659078924, 728.7340120855849, 775.6664634207232, 820.6840366458837, 841.469873979389, 5468.261784659099, 5581.685805904876, 5668.914966275509, 5691.203231547586, 5914.792982245601, 865.7575878753833, 896.2023798819056, 903.8192361527589, 907.0476000199764, 915.9358844880794, 3550.6449355064287, 3562.686701367701, 3573.8927832165077, 3595.5146055282203, 3750.043738846631, 9349.953250233706, 9694.75763105914, 9785.608575657036, 9811.362261452983, 9862.852057219148, 9562.856557151648, 9754.317197796228, 9771.267154963263, 9797.755056123653, 9877.50612469388, 7084.787456376317, 7203.171635261907, 7205.243449437734, 7206.963965180141, 7207.783766487014, 5104.744762761773, 5226.71253081069, 5264.815731449381, 5287.339082614673, 5475.041867673221, 8933.330000250136, 9046.819063618823, 9105.180533751995, 9113.316501262565, 9119.316051296046, 9064.546772661472, 9216.124468175512, 9310.162085412345, 9327.766805829908, 9359.578818953027, 5601.243832082617, 6063.151158837854, 6223.595466294676, 6368.36316368378, 6384.106225128578, 7465.1788303287685, 7731.072271327476, 7763.0684317882215, 7772.4947878389985, 7781.727639532589, 6803.7618683345845, 6847.109875716125, 6869.690863911036, 6880.552764070319, 7044.119485064367, 6615.040819081302, 6693.09643198162, 6769.221539523018, 6944.618046007518, 6949.548279361826, 6138.785142519991, 6212.099245609452, 6226.3151053385, 6233.189685340877, 6235.0, 4513.819447222074, 4573.931391029204, 4575.65682573815, 4701.318308845267, 4773.737444440589, 4533.478649955297, 4535.365048893092, 4702.459217190067, 4702.623790096715, 4899.0], "value": 4094.0334988041714, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.6425097130531, 294.4596665119506, 303.2797106871181, 306.7545963229417, 307.4757538397033, 4959.6032317414565, 4961.776720047597, 5002.79988800448, 5006.573126658898, 5024.497550244973, 4591.127685739707, 4706.62347012239, 4752.976235118819, 4784.234522476405, 4897.0, 3850.634189751976, 3864.381698928172, 3883.669888059504, 3892.8978351690193, 3897.941530877039, 3892.4355968384566, 3896.454496370496, 3901.102746368337, 3916.8824935251987, 3924.3721004639265, 3608.9097772555765, 3739.813009349535, 3843.404272337778, 3868.3810590305557, 3884.844606215748, 3827.885163445101, 3836.635519625631, 3842.923141537168, 3877.7673339599573, 3882.669973052294, 3399.6260411354733, 3404.438267685829, 3413.044347582681, 3418.6410426905313, 3428.674275943806, 4678.883027924295, 4744.786484608201, 4757.762111894392, 4773.856784296477, 4802.375691160127, 4349.3041113421705, 4349.3258544925275, 4364.912701745979, 4410.16206920684, 4421.756803375813, 5118.846434606968, 5142.151544995071, 5149.348085735849, 5153.742312884378, 5155.793768249266, 3685.539307586552, 3702.4816525686424, 3702.5556933167923, 3715.7584756990977, 3724.7951362675312, 5126.948730512684, 5320.334958130234, 5325.334333208349, 5329.413764485865, 5381.623286369956, 3811.5045044144085, 3817.97647246954, 3822.7515211511163, 3837.424386342015, 3837.6162383761402, 3878.864239751595, 3881.84472621092, 3889.9416508752392, 3906.4335671328067, 3920.3923391873736, 4291.849785257501, 4373.781310934441, 4380.6057454829215, 4421.7789110544345, 4426.557344265548, 5068.543831055149, 5081.59347252219, 5089.0, 5094.1849304111165, 5115.488451154929, 1331.940062697181, 1338.8928885689122, 1340.9865901341057, 1343.4961889291515, 1346.8619016930677, 830.3191383065948, 874.173905659146, 896.802768304321, 916.6883259691737, 930.7393929699695, 4452.48796388413, 4452.732836029849, 4454.844080457168, 4457.5988161065015, 4461.776911154494, 5136.486351364908, 5144.742762861842, 5161.200013997836, 5170.819021334235, 5191.247269146028, 4291.592298731615, 4414.624756895636, 4430.253242989303, 4449.043455657049, 4529.479109902338, 2799.5800629905675, 2804.6914839367655, 2809.845458499802, 2810.690824009357, 2813.7045610211044, 4927.655064145547, 4951.925721114186, 4953.826616068421, 4967.528084831935, 4968.726720030433, 5182.896342073137, 5436.836894893159, 5441.0, 5444.537214336747, 5467.17992301158, 4960.065574425977, 4967.503249675004, 4968.577670897942, 4970.130227210224, 4974.283872064132, 5336.386315573759, 5342.11855043929, 5348.866278342917, 5362.731863406736, 5374.408815030265, 5112.542925266282, 5159.742012899265, 5181.165937206515, 5191.610629202901, 5197.532222100029, 4943.629227808001, 4946.703197808143, 4982.2811130160035, 4990.301357809837, 5013.749312534432, 3752.212035472581, 3756.7039371416818, 3777.33896568105, 3778.7677800863207, 3786.223824116105, 5152.922706159336, 5296.682199068068, 5297.88744363688, 5315.2292917525965, 5400.540954018952, 5108.948910510847, 5136.743162841918, 5148.330717006766, 5163.199704045879, 5167.61242906791, 5146.214020960542, 5158.484151584811, 5163.882808048832, 5172.741362931763, 5191.065608190562, 4843.24929635907, 4971.254311853251, 5008.799648014185, 5012.1980483122525, 5020.970701006358, 4886.242632391985, 4972.602191824573, 5132.538071573576, 5154.716490593053, 5160.406553246425, 5012.147934851157, 5017.422996355466, 5051.0, 5075.568576670877, 5084.822031229037], "value": 3880.039966810838, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.2771876201748, 664.007309072936, 667.4460198035631, 729.51122747759, 732.8864026075956, 12651.999999999989, 13790.517331893374, 13810.309484525784, 13831.654208644764, 13841.930790346047, 13305.534306299282, 13383.263920484369, 13409.798853017188, 13427.395767190475, 13504.0, 622.3185611755143, 751.954882707038, 758.7647829172946, 776.7980325115483, 780.4965797060893, 779.7115067425033, 781.1212386065677, 781.136843787615, 781.6990458673423, 782.2803021220462, 817.627979269431, 818.0510607695074, 818.7420962396856, 818.9426740128193, 819.4509678515409, 654.6628486329544, 785.7839094249127, 806.5967016491754, 809.1463506001166, 810.5866008335732, 593.6912805341242, 594.0702800117845, 594.3610618584995, 594.3759052994338, 594.4263785447064, 13155.07914445989, 13190.60428187156, 13205.603831884962, 13206.0, 13221.669458263621, 12706.936465317707, 12798.61604151877, 12986.155899866479, 13164.736705265841, 13472.393742281622, 11406.54373825046, 11409.600663976804, 11591.76816463666, 11639.35983520906, 12036.578719744766, 4020.557738648746, 4042.6967977401814, 4132.462779838632, 4140.275451795924, 4204.348326009474, 59034.0, 60262.096068559, 61045.0, 61502.38497615056, 61642.0, 9650.324477286664, 10001.0, 10031.197504199647, 10369.066783989478, 10400.895991039994, 18050.909745451452, 18366.081695915418, 18375.356862509747, 18377.15154151082, 18388.17253223608, 6454.774082907075, 6488.902666460007, 6515.543911926215, 6702.530822842355, 6771.5259931805285, 3597.874074407383, 3657.9259193154326, 3683.226522430266, 3705.444183372516, 3707.1844194277214, 2825.406664600457, 2926.6049083373473, 2931.9267018324713, 2935.706429357047, 2936.6916473769948, 537.7042626555457, 545.021686073499, 545.2747845365733, 548.7722595123059, 569.3367227180371, 528.6642981706567, 530.0538538708387, 556.8468671115476, 572.8457158824971, 574.5145352177403, 7461.888071678929, 7844.764657060297, 7861.2531809478005, 7972.927038969794, 8005.639746211435, 504.64674727690766, 537.8897326048151, 574.1703238819948, 608.6500262349078, 621.487272999777, 3558.893233203008, 3654.6162652921594, 3663.19409729859, 3798.4682144499793, 3897.2984862724416, 83139.16860831314, 86077.0, 87106.0, 88509.55745221362, 88879.55560222158, 75798.62100689572, 75836.0, 76265.0, 77305.0, 78068.60965695135, 65563.67218163975, 67415.66292168606, 67448.66275668591, 68974.31025689679, 71227.09661292339, 7018.067642946411, 7210.459215558959, 7226.40244356697, 7236.927630723836, 7407.370383513775, 36733.44899826558, 36763.0, 38158.0, 42957.0, 43354.13291734211, 39874.800625996686, 47504.0, 52588.47411525836, 53896.0, 62573.37426625858, 11746.412679365827, 11773.587924422603, 11843.111766617365, 12234.082443816571, 12303.507859685513, 12786.168899021533, 13015.284159371326, 13171.143875648053, 13196.340182991004, 13247.138935969131, 10414.218933580163, 10577.206709496664, 11296.152788541056, 11452.198346116025, 11656.592019279284, 11654.0, 11841.111916606116, 11855.34795586251, 12484.500619975099, 12488.25070495773, 11430.48562814692, 11472.254303470248, 11939.403029848647, 11993.580224692443, 12177.147599667938, 9428.717138485856, 9658.806823863624, 9735.70792876215, 9749.756256093655, 10144.543495542866, 8719.69904581574, 8983.550822458981, 9331.813363732823, 9860.30977831545, 9914.752131196778], "value": 6442.062090502502, "unit": "score"}], "name": "dromaeo_css", "value": 6471.45425747142, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 115.0, 114.0, 115.0, 115.0, 114.0, 113.0, 114.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 130.0, 186.0, 125.0, 126.0, 156.0, 127.0, 165.0, 122.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 131.0, 79.0, 76.0, 75.0, 76.0, 99.0, 75.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 117.0, 85.0, 86.0, 86.0, 114.0, 86.0, 86.0, 120.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 148.0, 147.0, 147.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 69.0, 70.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 89.0, 80.0, 79.0, 89.0, 79.0, 79.0, 79.0, 79.0, 78.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 128.0, 129.0, 141.0, 128.0, 127.0, 129.0, 132.0, 223.0, 131.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 163.0, 162.0, 161.0, 167.0, 164.0, 165.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1533.8999999999999, "alertThreshold": 2.0}]} 04:08:43 INFO - Return code: 0 04:08:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:08:43 INFO - # TBPL SUCCESS # 04:08:43 INFO - Running post-action listener: _resource_record_post_action 04:08:43 INFO - [mozharness: 2016-07-22 11:08:43.508707Z] Finished run-tests step (success) 04:08:43 INFO - Running post-run listener: _resource_record_post_run 04:08:43 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 8192; Write bytes: 551641088; Read time: 256; Write time: 1243912 04:08:43 INFO - TinderboxPrint: CPU usage
13.0% 04:08:43 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 256 04:08:43 INFO - TinderboxPrint: I/O write bytes / time
551,641,088 / 1,243,912 04:08:43 INFO - TinderboxPrint: CPU idle
8,343.5 (87.0%) 04:08:43 INFO - TinderboxPrint: CPU user
1,199.1 (12.5%) 04:08:43 INFO - TinderboxPrint: Swap in / out
0 / 0 04:08:43 INFO - install - Wall time: 17s; CPU: 18.0%; Read bytes: 0; Write bytes: 261808128; Read time: 0; Write time: 920176 04:08:43 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289832960; Read time: 212; Write time: 323736 04:08:44 INFO - Running post-run listener: _upload_blobber_files 04:08:44 INFO - Blob upload gear active. 04:08:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:08:44 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 04:08:44 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:08:44 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:08:44 INFO - (blobuploader) - INFO - Open directory for files ... 04:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:09:05 INFO - (blobuploader) - INFO - Done attempting. 04:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:09:06 INFO - (blobuploader) - INFO - Done attempting. 04:09:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:09:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:09:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:09:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:09:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:09:07 INFO - (blobuploader) - INFO - Done attempting. 04:09:07 INFO - (blobuploader) - INFO - Iteration through files over. 04:09:07 INFO - Return code: 0 04:09:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:09:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:09:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9ac23a0c54bb4f1bf424b33e05c45feb4cf1bb7edca3658a254ea559411819855394df65fd91eec7dffc3e083e9fa0c51342c6b0c273496fcdf35ad0c3a9ac33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d5aec55287651a58f105bdb20642be6110404c1bcefae239df7c49f6ea27231517b15a76f7ee5dde96b669bb1c6648a58b483abaccfcb71986a256b1e9dfcf31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fd1f8464ae761158c36d62777d12acc47fe30e53a6c7f2df729975085a4aee5cdbc147dc6f5ace1359f59b79af299d21a8c59cffcee8953d2e813b9e424b522f"} 04:09:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:09:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:09:07 INFO - Contents: 04:09:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9ac23a0c54bb4f1bf424b33e05c45feb4cf1bb7edca3658a254ea559411819855394df65fd91eec7dffc3e083e9fa0c51342c6b0c273496fcdf35ad0c3a9ac33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d5aec55287651a58f105bdb20642be6110404c1bcefae239df7c49f6ea27231517b15a76f7ee5dde96b669bb1c6648a58b483abaccfcb71986a256b1e9dfcf31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fd1f8464ae761158c36d62777d12acc47fe30e53a6c7f2df729975085a4aee5cdbc147dc6f5ace1359f59b79af299d21a8c59cffcee8953d2e813b9e424b522f"} 04:09:07 INFO - Running post-run listener: copy_logs_to_upload_dir 04:09:07 INFO - Copying logs to upload dir... 04:09:07 INFO - mkdir: /builds/slave/test/build/upload/logs 04:09:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.380599 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-22 04:09:07.223554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:09:07.228007) ========= 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=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 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-Non-PGO/sha512/9ac23a0c54bb4f1bf424b33e05c45feb4cf1bb7edca3658a254ea559411819855394df65fd91eec7dffc3e083e9fa0c51342c6b0c273496fcdf35ad0c3a9ac33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d5aec55287651a58f105bdb20642be6110404c1bcefae239df7c49f6ea27231517b15a76f7ee5dde96b669bb1c6648a58b483abaccfcb71986a256b1e9dfcf31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fd1f8464ae761158c36d62777d12acc47fe30e53a6c7f2df729975085a4aee5cdbc147dc6f5ace1359f59b79af299d21a8c59cffcee8953d2e813b9e424b522f"} build_url:https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/fy_1s_GLQxqqdFMQUbNmEg/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-Non-PGO/sha512/9ac23a0c54bb4f1bf424b33e05c45feb4cf1bb7edca3658a254ea559411819855394df65fd91eec7dffc3e083e9fa0c51342c6b0c273496fcdf35ad0c3a9ac33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d5aec55287651a58f105bdb20642be6110404c1bcefae239df7c49f6ea27231517b15a76f7ee5dde96b669bb1c6648a58b483abaccfcb71986a256b1e9dfcf31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fd1f8464ae761158c36d62777d12acc47fe30e53a6c7f2df729975085a4aee5cdbc147dc6f5ace1359f59b79af299d21a8c59cffcee8953d2e813b9e424b522f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:09:07.260132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:09:07.260800) ========= 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=df17445c3590ff7897617a0e00000225-1469184290.969369-1855171247 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:09:07.344149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:09:07.344571) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:09:07.344960) ========= ========= Total master_lag: 0.19 =========