builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1467865368.7 results: success (0) buildid: 20160706193622 builduid: 041331d3b3814245a20f3f7d8ae8a679 revision: c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.702186) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.702758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.703059) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.718589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.718981) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-06 21:22:48-- 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% 100M=0s 2016-07-06 21:22:48 (100 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073241 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.806915) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.807244) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016073 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.857572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:48.857942) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 --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 c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-06 21:22:48,867 truncating revision to first 12 chars 2016-07-06 21:22:48,868 Setting DEBUG logging. 2016-07-06 21:22:48,868 attempt 1/10 2016-07-06 21:22:48,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c0e2702226bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:22:49,183 unpacking tar archive at: fx-team-c0e2702226bb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649040 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:49.520815) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:49.521165) ========= script_repo_revision: c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:49.521603) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:49.521905) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 21:22:49.533216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-06 21:22:49.533639) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:22:49 INFO - MultiFileLogger online at 20160706 21:22:49 in /builds/slave/test 21:22:49 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 21:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:22:49 INFO - {'append_to_log': False, 21:22:49 INFO - 'base_work_dir': '/builds/slave/test', 21:22:49 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:22:49 INFO - 'branch': 'Fx-Team-Non-PGO', 21:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 21:22:49 INFO - 'config_files': ('talos/linux_config.py',), 21:22:49 INFO - 'default_actions': ('clobber', 21:22:49 INFO - 'read-buildbot-config', 21:22:49 INFO - 'download-and-extract', 21:22:49 INFO - 'populate-webroot', 21:22:49 INFO - 'create-virtualenv', 21:22:49 INFO - 'install', 21:22:49 INFO - 'run-tests'), 21:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:22:49 INFO - 'download_minidump_stackwalk': True, 21:22:49 INFO - 'download_symbols': 'ondemand', 21:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 21:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 21:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:22:49 INFO - 'installer_path': 'installer.exe', 21:22:49 INFO - 'log_level': 'info', 21:22:49 INFO - 'log_name': 'talos', 21:22:49 INFO - 'log_to_console': True, 21:22:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:22:49 INFO - 'opt_config_files': (), 21:22:49 INFO - 'pip_index': False, 21:22:49 INFO - 'sps_profile': False, 21:22:49 INFO - 'sps_profile_interval': 0, 21:22:49 INFO - 'suite': 'dromaeojs', 21:22:49 INFO - 'system_bits': '32', 21:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:22:49 INFO - 'title': 'talos-linux64-ix-006', 21:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:22:49 INFO - 'use_talos_json': True, 21:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:22:49 INFO - 'work_dir': 'build'} 21:22:49 INFO - ##### 21:22:49 INFO - ##### Running clobber step. 21:22:49 INFO - ##### 21:22:49 INFO - Running pre-action listener: _resource_record_pre_action 21:22:49 INFO - Running main action method: clobber 21:22:49 INFO - rmtree: /builds/slave/test/build 21:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:22:50 INFO - Running post-action listener: _resource_record_post_action 21:22:50 INFO - ##### 21:22:50 INFO - ##### Finished clobber step (success) 21:22:50 INFO - ##### 21:22:50 INFO - ##### 21:22:50 INFO - ##### Running read-buildbot-config step. 21:22:50 INFO - ##### 21:22:50 INFO - Running pre-action listener: _resource_record_pre_action 21:22:50 INFO - Running main action method: read_buildbot_config 21:22:50 INFO - Using buildbot properties: 21:22:50 INFO - { 21:22:50 INFO - "project": "", 21:22:50 INFO - "product": "firefox", 21:22:50 INFO - "script_repo_revision": "production", 21:22:50 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:22:50 INFO - "repository": "", 21:22:50 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:22:50 INFO - "buildid": "20160706193622", 21:22:50 INFO - "pgo_build": "False", 21:22:50 INFO - "basedir": "/builds/slave/test", 21:22:50 INFO - "buildnumber": 1938, 21:22:50 INFO - "slavename": "talos-linux64-ix-006", 21:22:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:22:50 INFO - "platform": "ubuntu64_hw", 21:22:50 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:22:50 INFO - "branch": "fx-team", 21:22:50 INFO - "repo_path": "integration/fx-team", 21:22:50 INFO - "revision": "c0e2702226bbb18cc6f907e0a6c41e2bc3727e74", 21:22:50 INFO - "stage_platform": "linux64", 21:22:50 INFO - "builduid": "041331d3b3814245a20f3f7d8ae8a679", 21:22:50 INFO - "slavebuilddir": "test" 21:22:50 INFO - } 21:22:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:22:50 INFO - Running post-action listener: _resource_record_post_action 21:22:50 INFO - ##### 21:22:50 INFO - ##### Finished read-buildbot-config step (success) 21:22:50 INFO - ##### 21:22:50 INFO - ##### 21:22:50 INFO - ##### Running download-and-extract step. 21:22:50 INFO - ##### 21:22:50 INFO - Running pre-action listener: _resource_record_pre_action 21:22:50 INFO - Running main action method: download_and_extract 21:22:50 INFO - mkdir: /builds/slave/test/build/tests 21:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:22:50 INFO - https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:50 INFO - Downloaded 1361 bytes. 21:22:50 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:22:50 INFO - Using the following test package requirements: 21:22:50 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:22:50 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:22:50 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:22:50 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'jsshell-linux-x86_64.zip'], 21:22:50 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:22:50 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:22:50 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:22:50 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:22:50 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:22:50 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:22:50 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:22:50 INFO - https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:51 INFO - Downloaded 22733087 bytes. 21:22:51 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'] 21:22:51 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 21:22:53 INFO - Return code: 0 21:22:53 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 21:22:53 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')]} 21:22:53 INFO - https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:54 INFO - Downloaded 22733087 bytes. 21:22:54 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'] 21:22:54 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 21:22:55 INFO - Return code: 0 21:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:22:55 INFO - https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:22:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:56 INFO - Downloaded 14403333 bytes. 21:22:56 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'] 21:22:56 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 21:22:56 INFO - Return code: 0 21:22:56 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')]} 21:22:56 INFO - https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:22:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:22:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:22:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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 21:22:58 INFO - Downloaded 56585029 bytes. 21:22:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:22:58 INFO - mkdir: /builds/slave/test/properties 21:22:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:22:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:22:58 INFO - Contents: 21:22:58 INFO - build_url:https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:22:59 INFO - Running post-action listener: _resource_record_post_action 21:22:59 INFO - Running post-action listener: set_extra_try_arguments 21:22:59 INFO - ##### 21:22:59 INFO - ##### Finished download-and-extract step (success) 21:22:59 INFO - ##### 21:22:59 INFO - ##### 21:22:59 INFO - ##### Running populate-webroot step. 21:22:59 INFO - ##### 21:22:59 INFO - Running pre-action listener: _resource_record_pre_action 21:22:59 INFO - Running main action method: populate_webroot 21:22:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:22:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:22:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:22:59 INFO - '--tppagecycles', 21:22:59 INFO - '7'], 21:22:59 INFO - 'tests': ['tsvgm']}}, 21:22:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:22:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:22:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:22:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:22:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:22:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:22:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['damp', 'tps']}, 21:22:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'tests': ['damp', 'tps']}, 21:22:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['dromaeo_dom']}, 21:22:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:22:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['basic_compositor_video']}, 21:22:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:22:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'other-e10s': {'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['a11yr', 21:22:59 INFO - 'ts_paint', 21:22:59 INFO - 'tpaint', 21:22:59 INFO - 'sessionrestore', 21:22:59 INFO - 'sessionrestore_no_auto_restore', 21:22:59 INFO - 'tabpaint']}, 21:22:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['tsvgx', 21:22:59 INFO - 'tsvgr_opacity', 21:22:59 INFO - 'tart', 21:22:59 INFO - 'tscrollx', 21:22:59 INFO - 'cart']}, 21:22:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:22:59 INFO - 'tsvgr_opacity', 21:22:59 INFO - 'tart', 21:22:59 INFO - 'tscrollx', 21:22:59 INFO - 'cart']}, 21:22:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'talos_options': ['--disable-e10s'], 21:22:59 INFO - 'tests': ['tp5o']}, 21:22:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'tests': ['tp5o']}, 21:22:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'talos_options': ['--disable-e10s', 21:22:59 INFO - '--xperf_path', 21:22:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:22:59 INFO - 'tests': ['tp5n']}, 21:22:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:22:59 INFO - 'talos_options': ['--xperf_path', 21:22:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:22:59 INFO - 'tests': ['tp5n']}}, 21:22:59 INFO - 'talos.zip': {'path': '', 21:22:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:22:59 INFO - Running post-action listener: _resource_record_post_action 21:22:59 INFO - ##### 21:22:59 INFO - ##### Finished populate-webroot step (success) 21:22:59 INFO - ##### 21:22:59 INFO - ##### 21:22:59 INFO - ##### Running create-virtualenv step. 21:22:59 INFO - ##### 21:22:59 INFO - Running pre-action listener: _resource_record_pre_action 21:22:59 INFO - Running main action method: create_virtualenv 21:22:59 INFO - Creating virtualenv /builds/slave/test/build/venv 21:22:59 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 21:22:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:22:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:22:59 INFO - Using real prefix '/usr' 21:22:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:22:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:23:01 INFO - Installing pip.................done. 21:23:01 INFO - Return code: 0 21:23:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:23:01 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')]} 21:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:01 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')]} 21:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:01 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:01 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 21:23:01 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 21:23:01 INFO - Using env: {'DISPLAY': ':0', 21:23:01 INFO - 'HOME': '/home/cltbld', 21:23:01 INFO - 'LANG': 'en_US.UTF-8', 21:23:01 INFO - 'LANGUAGE': 'en_US:en', 21:23:01 INFO - 'LOGNAME': 'cltbld', 21:23:01 INFO - 'MAIL': '/var/mail/cltbld', 21:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:01 INFO - 'MOZ_NO_REMOTE': '1', 21:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:01 INFO - 'NO_EM_RESTART': '1', 21:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:01 INFO - 'PWD': '/builds/slave/test', 21:23:01 INFO - 'SHELL': '/bin/bash', 21:23:01 INFO - 'SHLVL': '1', 21:23:01 INFO - 'TERM': 'linux', 21:23:01 INFO - 'TMOUT': '86400', 21:23:01 INFO - 'USER': 'cltbld', 21:23:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:01 INFO - '_': '/tools/buildbot/bin/python'} 21:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:01 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 21:23:01 INFO - Cleaning up... 21:23:01 INFO - Return code: 0 21:23:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:23:01 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')]} 21:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:01 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')]} 21:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:01 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:01 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 21:23:01 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 21:23:01 INFO - Using env: {'DISPLAY': ':0', 21:23:01 INFO - 'HOME': '/home/cltbld', 21:23:01 INFO - 'LANG': 'en_US.UTF-8', 21:23:01 INFO - 'LANGUAGE': 'en_US:en', 21:23:01 INFO - 'LOGNAME': 'cltbld', 21:23:01 INFO - 'MAIL': '/var/mail/cltbld', 21:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:01 INFO - 'MOZ_NO_REMOTE': '1', 21:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:01 INFO - 'NO_EM_RESTART': '1', 21:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:01 INFO - 'PWD': '/builds/slave/test', 21:23:01 INFO - 'SHELL': '/bin/bash', 21:23:01 INFO - 'SHLVL': '1', 21:23:01 INFO - 'TERM': 'linux', 21:23:01 INFO - 'TMOUT': '86400', 21:23:01 INFO - 'USER': 'cltbld', 21:23:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:01 INFO - '_': '/tools/buildbot/bin/python'} 21:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:02 INFO - Downloading/unpacking psutil>=3.1.1 21:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:23:04 INFO - Installing collected packages: psutil 21:23:04 INFO - Running setup.py install for psutil 21:23:04 INFO - building 'psutil._psutil_linux' extension 21:23:04 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 21:23:04 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 21:23:05 INFO - building 'psutil._psutil_posix' extension 21:23:05 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 21:23:05 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 21:23:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:23:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:23:05 INFO - Successfully installed psutil 21:23:05 INFO - Cleaning up... 21:23:05 INFO - Return code: 0 21:23:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:05 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:05 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 21:23:05 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 21:23:05 INFO - Using env: {'DISPLAY': ':0', 21:23:05 INFO - 'HOME': '/home/cltbld', 21:23:05 INFO - 'LANG': 'en_US.UTF-8', 21:23:05 INFO - 'LANGUAGE': 'en_US:en', 21:23:05 INFO - 'LOGNAME': 'cltbld', 21:23:05 INFO - 'MAIL': '/var/mail/cltbld', 21:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:05 INFO - 'MOZ_NO_REMOTE': '1', 21:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:05 INFO - 'NO_EM_RESTART': '1', 21:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:05 INFO - 'PWD': '/builds/slave/test', 21:23:05 INFO - 'SHELL': '/bin/bash', 21:23:05 INFO - 'SHLVL': '1', 21:23:05 INFO - 'TERM': 'linux', 21:23:05 INFO - 'TMOUT': '86400', 21:23:05 INFO - 'USER': 'cltbld', 21:23:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:05 INFO - '_': '/tools/buildbot/bin/python'} 21:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:23:05 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 21:23:05 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 21:23:05 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 21:23:05 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 21:23:05 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 21:23:05 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 21:23:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:23:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:23:07 INFO - Installing collected packages: mozsystemmonitor 21:23:07 INFO - Running setup.py install for mozsystemmonitor 21:23:07 INFO - Successfully installed mozsystemmonitor 21:23:07 INFO - Cleaning up... 21:23:07 INFO - Return code: 0 21:23:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:07 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:07 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 21:23:07 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 21:23:07 INFO - Using env: {'DISPLAY': ':0', 21:23:07 INFO - 'HOME': '/home/cltbld', 21:23:07 INFO - 'LANG': 'en_US.UTF-8', 21:23:07 INFO - 'LANGUAGE': 'en_US:en', 21:23:07 INFO - 'LOGNAME': 'cltbld', 21:23:07 INFO - 'MAIL': '/var/mail/cltbld', 21:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:07 INFO - 'MOZ_NO_REMOTE': '1', 21:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:07 INFO - 'NO_EM_RESTART': '1', 21:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:07 INFO - 'PWD': '/builds/slave/test', 21:23:07 INFO - 'SHELL': '/bin/bash', 21:23:07 INFO - 'SHLVL': '1', 21:23:07 INFO - 'TERM': 'linux', 21:23:07 INFO - 'TMOUT': '86400', 21:23:07 INFO - 'USER': 'cltbld', 21:23:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:07 INFO - '_': '/tools/buildbot/bin/python'} 21:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:08 INFO - Downloading/unpacking jsonschema==2.5.1 21:23:08 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 21:23:08 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 21:23:08 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 21:23:08 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 21:23:08 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 21:23:08 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 21:23:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:23:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:23:09 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 21:23:09 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 21:23:09 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 21:23:09 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 21:23:09 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 21:23:09 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 21:23:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:23:10 INFO - warning: no files found matching '*.txt' 21:23:10 INFO - no previously-included directories found matching 'build' 21:23:10 INFO - no previously-included directories found matching 'dist' 21:23:10 INFO - no previously-included directories found matching '.git*' 21:23:10 INFO - Installing collected packages: jsonschema, functools32 21:23:10 INFO - Running setup.py install for functools32 21:23:10 INFO - warning: no files found matching '*.txt' 21:23:10 INFO - no previously-included directories found matching 'build' 21:23:10 INFO - no previously-included directories found matching 'dist' 21:23:10 INFO - no previously-included directories found matching '.git*' 21:23:10 INFO - Successfully installed jsonschema functools32 21:23:10 INFO - Cleaning up... 21:23:10 INFO - Return code: 0 21:23:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:23: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')]} 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23: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')]} 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:10 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:10 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 21:23:10 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 21:23:10 INFO - Using env: {'DISPLAY': ':0', 21:23:10 INFO - 'HOME': '/home/cltbld', 21:23:10 INFO - 'LANG': 'en_US.UTF-8', 21:23:10 INFO - 'LANGUAGE': 'en_US:en', 21:23:10 INFO - 'LOGNAME': 'cltbld', 21:23:10 INFO - 'MAIL': '/var/mail/cltbld', 21:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:10 INFO - 'MOZ_NO_REMOTE': '1', 21:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:10 INFO - 'NO_EM_RESTART': '1', 21:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:10 INFO - 'PWD': '/builds/slave/test', 21:23:10 INFO - 'SHELL': '/bin/bash', 21:23:10 INFO - 'SHLVL': '1', 21:23:10 INFO - 'TERM': 'linux', 21:23:10 INFO - 'TMOUT': '86400', 21:23:10 INFO - 'USER': 'cltbld', 21:23:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:10 INFO - '_': '/tools/buildbot/bin/python'} 21:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:10 INFO - Downloading/unpacking blobuploader==1.2.4 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:12 INFO - Downloading blobuploader-1.2.4.tar.gz 21:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:23:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:23:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:12 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 21:23:13 INFO - Downloading docopt-0.6.1.tar.gz 21:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:23:13 INFO - Installing collected packages: blobuploader, requests, docopt 21:23:13 INFO - Running setup.py install for blobuploader 21:23:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:23:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:23:13 INFO - Running setup.py install for requests 21:23:13 INFO - Running setup.py install for docopt 21:23:13 INFO - Successfully installed blobuploader requests docopt 21:23:13 INFO - Cleaning up... 21:23:13 INFO - Return code: 0 21:23:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:13 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:13 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 21:23:13 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 21:23:13 INFO - Using env: {'DISPLAY': ':0', 21:23:13 INFO - 'HOME': '/home/cltbld', 21:23:13 INFO - 'LANG': 'en_US.UTF-8', 21:23:13 INFO - 'LANGUAGE': 'en_US:en', 21:23:13 INFO - 'LOGNAME': 'cltbld', 21:23:13 INFO - 'MAIL': '/var/mail/cltbld', 21:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:13 INFO - 'MOZ_NO_REMOTE': '1', 21:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:13 INFO - 'NO_EM_RESTART': '1', 21:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:13 INFO - 'PWD': '/builds/slave/test', 21:23:13 INFO - 'SHELL': '/bin/bash', 21:23:13 INFO - 'SHLVL': '1', 21:23:13 INFO - 'TERM': 'linux', 21:23:13 INFO - 'TMOUT': '86400', 21:23:13 INFO - 'USER': 'cltbld', 21:23:13 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:13 INFO - '_': '/tools/buildbot/bin/python'} 21:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:23:14 INFO - Running setup.py (path:/tmp/pip-1u6aCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:23:14 INFO - Running setup.py (path:/tmp/pip-7ZdPus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:23:14 INFO - Running setup.py (path:/tmp/pip-mH23Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:23:14 INFO - Running setup.py (path:/tmp/pip-qtFFSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:23:14 INFO - Running setup.py (path:/tmp/pip-KuVhPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:23:14 INFO - Running setup.py (path:/tmp/pip-Fk3ojb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:23:14 INFO - Running setup.py (path:/tmp/pip-hC4g9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:23:14 INFO - Running setup.py (path:/tmp/pip-ddfGL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:23:14 INFO - Running setup.py (path:/tmp/pip-_cq0_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:23:14 INFO - Running setup.py (path:/tmp/pip-RTk9CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:23:14 INFO - Running setup.py (path:/tmp/pip-p1moNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:23:14 INFO - Running setup.py (path:/tmp/pip-gE6B7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:23:14 INFO - Running setup.py (path:/tmp/pip-ye9x1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:23:15 INFO - Running setup.py (path:/tmp/pip-BiMlTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:23:15 INFO - Running setup.py (path:/tmp/pip-X7AHNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:23:15 INFO - Running setup.py (path:/tmp/pip-L3LaKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:23:15 INFO - Running setup.py (path:/tmp/pip-Y3OF6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:23:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:23:15 INFO - Running setup.py install for manifestparser 21:23:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:23:15 INFO - Running setup.py install for mozcrash 21:23:15 INFO - Running setup.py install for mozdebug 21:23:15 INFO - Running setup.py install for mozdevice 21:23:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:23:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:23:15 INFO - Running setup.py install for mozfile 21:23:15 INFO - Running setup.py install for mozhttpd 21:23:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:23:15 INFO - Running setup.py install for mozinfo 21:23:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for mozInstall 21:23:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for mozleak 21:23:16 INFO - Running setup.py install for mozlog 21:23:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for moznetwork 21:23:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for mozprocess 21:23:16 INFO - Running setup.py install for mozprofile 21:23:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for mozrunner 21:23:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:23:16 INFO - Running setup.py install for mozscreenshot 21:23:16 INFO - Running setup.py install for moztest 21:23:16 INFO - Running setup.py install for mozversion 21:23:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:23:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:23:17 INFO - Cleaning up... 21:23:17 INFO - Return code: 0 21:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:17 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:17 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 21:23:17 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 21:23:17 INFO - Using env: {'DISPLAY': ':0', 21:23:17 INFO - 'HOME': '/home/cltbld', 21:23:17 INFO - 'LANG': 'en_US.UTF-8', 21:23:17 INFO - 'LANGUAGE': 'en_US:en', 21:23:17 INFO - 'LOGNAME': 'cltbld', 21:23:17 INFO - 'MAIL': '/var/mail/cltbld', 21:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:17 INFO - 'MOZ_NO_REMOTE': '1', 21:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:17 INFO - 'NO_EM_RESTART': '1', 21:23:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:17 INFO - 'PWD': '/builds/slave/test', 21:23:17 INFO - 'SHELL': '/bin/bash', 21:23:17 INFO - 'SHLVL': '1', 21:23:17 INFO - 'TERM': 'linux', 21:23:17 INFO - 'TMOUT': '86400', 21:23:17 INFO - 'USER': 'cltbld', 21:23:17 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:17 INFO - '_': '/tools/buildbot/bin/python'} 21:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:23:17 INFO - Running setup.py (path:/tmp/pip-emeFZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:23:17 INFO - Running setup.py (path:/tmp/pip-8cVKib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:23:17 INFO - Running setup.py (path:/tmp/pip-g6v0is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:23:17 INFO - Running setup.py (path:/tmp/pip-IWOyib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:23:17 INFO - Running setup.py (path:/tmp/pip-sLgBcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:23:17 INFO - Running setup.py (path:/tmp/pip-00Zt8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:23:17 INFO - Running setup.py (path:/tmp/pip-mxb2t5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:23:17 INFO - Running setup.py (path:/tmp/pip-dmh2VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:23:17 INFO - Running setup.py (path:/tmp/pip-gHN3Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:23:17 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)) 21:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:23:17 INFO - Running setup.py (path:/tmp/pip-K8Fy1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:23:18 INFO - Running setup.py (path:/tmp/pip-zZfLfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:23:18 INFO - Running setup.py (path:/tmp/pip-ow8dZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:23:18 INFO - Running setup.py (path:/tmp/pip-yoI8Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:23:18 INFO - Running setup.py (path:/tmp/pip-UpZl2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:23:18 INFO - Running setup.py (path:/tmp/pip-tuxbVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:23:18 INFO - Running setup.py (path:/tmp/pip-eO3tNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:23:18 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)) 21:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:23:18 INFO - Running setup.py (path:/tmp/pip-wz7wko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:20 INFO - Downloading blessings-1.6.tar.gz 21:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:23:20 INFO - Installing collected packages: blessings 21:23:20 INFO - Running setup.py install for blessings 21:23:20 INFO - Successfully installed blessings 21:23:20 INFO - Cleaning up... 21:23:20 INFO - Return code: 0 21:23:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:23:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:23:20 INFO - Reading from file tmpfile_stdout 21:23:20 INFO - Current package versions: 21:23:20 INFO - argparse == 1.2.1 21:23:20 INFO - blessings == 1.6 21:23:20 INFO - blobuploader == 1.2.4 21:23:20 INFO - docopt == 0.6.1 21:23:20 INFO - functools32 == 3.2.3-2 21:23:20 INFO - jsonschema == 2.5.1 21:23:20 INFO - manifestparser == 1.1 21:23:20 INFO - mozInstall == 1.12 21:23:20 INFO - mozcrash == 0.17 21:23:20 INFO - mozdebug == 0.1 21:23:20 INFO - mozdevice == 0.48 21:23:20 INFO - mozfile == 1.2 21:23:20 INFO - mozhttpd == 0.7 21:23:20 INFO - mozinfo == 0.9 21:23:20 INFO - mozleak == 0.1 21:23:20 INFO - mozlog == 3.2 21:23:20 INFO - moznetwork == 0.27 21:23:20 INFO - mozprocess == 0.23 21:23:20 INFO - mozprofile == 0.28 21:23:20 INFO - mozrunner == 6.12 21:23:20 INFO - mozscreenshot == 0.1 21:23:20 INFO - mozsystemmonitor == 0.3 21:23:20 INFO - moztest == 0.7 21:23:20 INFO - mozversion == 1.4 21:23:20 INFO - psutil == 3.1.1 21:23:20 INFO - requests == 1.2.3 21:23:20 INFO - wsgiref == 0.1.2 21:23:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:23:20 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')]} 21:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:20 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')]} 21:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:20 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 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23:20 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 21:23:20 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 21:23:20 INFO - Using env: {'DISPLAY': ':0', 21:23:20 INFO - 'HOME': '/home/cltbld', 21:23:20 INFO - 'LANG': 'en_US.UTF-8', 21:23:20 INFO - 'LANGUAGE': 'en_US:en', 21:23:20 INFO - 'LOGNAME': 'cltbld', 21:23:20 INFO - 'MAIL': '/var/mail/cltbld', 21:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:20 INFO - 'MOZ_NO_REMOTE': '1', 21:23:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:20 INFO - 'NO_EM_RESTART': '1', 21:23:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:20 INFO - 'PWD': '/builds/slave/test', 21:23:20 INFO - 'SHELL': '/bin/bash', 21:23:20 INFO - 'SHLVL': '1', 21:23:20 INFO - 'TERM': 'linux', 21:23:20 INFO - 'TMOUT': '86400', 21:23:20 INFO - 'USER': 'cltbld', 21:23:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:20 INFO - '_': '/tools/buildbot/bin/python'} 21:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 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)) 21:23:21 INFO - Cleaning up... 21:23:21 INFO - Return code: 0 21:23:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:23: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')]} 21:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23: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')]} 21:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a98030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d59180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', '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 21:23: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', 'jsonschema'] in /builds/slave/test/build 21:23: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 jsonschema 21:23:21 INFO - Using env: {'DISPLAY': ':0', 21:23:21 INFO - 'HOME': '/home/cltbld', 21:23:21 INFO - 'LANG': 'en_US.UTF-8', 21:23:21 INFO - 'LANGUAGE': 'en_US:en', 21:23:21 INFO - 'LOGNAME': 'cltbld', 21:23:21 INFO - 'MAIL': '/var/mail/cltbld', 21:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:21 INFO - 'MOZ_NO_REMOTE': '1', 21:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:21 INFO - 'NO_EM_RESTART': '1', 21:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:21 INFO - 'PWD': '/builds/slave/test', 21:23:21 INFO - 'SHELL': '/bin/bash', 21:23:21 INFO - 'SHLVL': '1', 21:23:21 INFO - 'TERM': 'linux', 21:23:21 INFO - 'TMOUT': '86400', 21:23:21 INFO - 'USER': 'cltbld', 21:23:21 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:21 INFO - '_': '/tools/buildbot/bin/python'} 21:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:23:21 INFO - Cleaning up... 21:23:21 INFO - Return code: 0 21:23:21 INFO - Running post-action listener: _resource_record_post_action 21:23:21 INFO - Running post-action listener: _start_resource_monitoring 21:23:21 INFO - Starting resource monitoring. 21:23:21 INFO - ##### 21:23:21 INFO - ##### Finished create-virtualenv step (success) 21:23:21 INFO - ##### 21:23:21 INFO - ##### 21:23:21 INFO - ##### Running install step. 21:23:21 INFO - ##### 21:23:21 INFO - Running pre-action listener: _resource_record_pre_action 21:23:21 INFO - Running main action method: install 21:23:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:23:21 INFO - Reading from file tmpfile_stdout 21:23:21 INFO - Detecting whether we're running mozinstall >=1.0... 21:23:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:23:21 INFO - Reading from file tmpfile_stdout 21:23:21 INFO - Output received: 21:23:21 INFO - Usage: mozinstall [options] installer 21:23:21 INFO - Options: 21:23:21 INFO - -h, --help show this help message and exit 21:23:21 INFO - -d DEST, --destination=DEST 21:23:21 INFO - Directory to install application into. [default: 21:23:21 INFO - "/builds/slave/test"] 21:23:21 INFO - --app=APP Application being installed. [default: firefox] 21:23:21 INFO - mkdir: /builds/slave/test/build/application 21:23:21 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'] 21:23:21 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 21:23:37 INFO - Reading from file tmpfile_stdout 21:23:37 INFO - Output received: 21:23:37 INFO - /builds/slave/test/build/application/firefox/firefox 21:23:37 INFO - Running post-action listener: _resource_record_post_action 21:23:37 INFO - ##### 21:23:37 INFO - ##### Finished install step (success) 21:23:37 INFO - ##### 21:23:37 INFO - ##### 21:23:37 INFO - ##### Running run-tests step. 21:23:37 INFO - ##### 21:23:37 INFO - Running pre-action listener: _resource_record_pre_action 21:23:37 INFO - Running main action method: run_tests 21:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:23:37 INFO - Python 2.7.3 21:23:37 INFO - Return code: 0 21:23:37 INFO - grabbing minidump binary from tooltool 21:23:37 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')]} 21:23:37 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 0x1d573b0>, '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 0x1d582b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d58740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:23:37 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 21:23:37 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 21:23:37 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 21:23:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:23:38 INFO - Return code: 0 21:23:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:23:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:23:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:23:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:23:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:23:38 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/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:23:38 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/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:23:38 INFO - Using env: {'DISPLAY': ':0', 21:23:38 INFO - 'HOME': '/home/cltbld', 21:23:38 INFO - 'LANG': 'en_US.UTF-8', 21:23:38 INFO - 'LANGUAGE': 'en_US:en', 21:23:38 INFO - 'LOGNAME': 'cltbld', 21:23:38 INFO - 'MAIL': '/var/mail/cltbld', 21:23:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:23:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:38 INFO - 'MOZ_NO_REMOTE': '1', 21:23:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:23:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:38 INFO - 'NO_EM_RESTART': '1', 21:23:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:23:38 INFO - 'PWD': '/builds/slave/test', 21:23:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:23:38 INFO - 'SHELL': '/bin/bash', 21:23:38 INFO - 'SHLVL': '1', 21:23:38 INFO - 'TERM': 'linux', 21:23:38 INFO - 'TMOUT': '86400', 21:23:38 INFO - 'USER': 'cltbld', 21:23:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405', 21:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:38 INFO - '_': '/tools/buildbot/bin/python'} 21:23:38 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/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:23:38 INFO - mozversion application_buildid: 20160706193622 21:23:38 INFO - mozversion application_changeset: c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 21:23:38 INFO - mozversion application_display_name: Nightly 21:23:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:23:38 INFO - mozversion application_name: Firefox 21:23:38 INFO - mozversion application_remotingname: firefox 21:23:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:23:38 INFO - mozversion application_vendor: Mozilla 21:23:38 INFO - mozversion application_version: 50.0a1 21:23:38 INFO - mozversion platform_buildid: 20160706193622 21:23:38 INFO - mozversion platform_changeset: c0e2702226bbb18cc6f907e0a6c41e2bc3727e74 21:23:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:23:38 INFO - mozversion platform_version: 50.0a1 21:23:38 INFO - using testdate: 1467865418 21:23:38 INFO - actual date: 1467865418 21:23:38 INFO - starting webserver on 'localhost:44186' 21:23:38 INFO - SUITE-START | Running 2 tests 21:23:38 INFO - TEST-START | dromaeo_css 21:23:38 INFO - operating with platform_type : linux_ 21:23:38 INFO - Initialising browser for dromaeo_css test... 21:23:38 INFO - TEST-INFO | started process 7064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3HoFpj/profile http://localhost:44186/getInfo.html) 21:23:42 INFO - PROCESS | 7064 | __metrics Screen width/height:1600/1200 21:23:42 INFO - PROCESS | 7064 | colorDepth:24 21:23:42 INFO - PROCESS | 7064 | Browser inner width/height: 1024/697 21:23:42 INFO - PROCESS | 7064 | __metrics 21:23:43 INFO - PROCESS | 7064 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:23:44 INFO - TEST-INFO | 7064: exit 0 21:23:44 INFO - Browser initialized. 21:23:44 INFO - Running cycle 1/1 for dromaeo_css test... 21:23:44 INFO - TEST-INFO | started process 7145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3HoFpj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:23:56 INFO - PROCESS | 7145 | RSS: Main: 159363072 21:23:56 INFO - PROCESS | 7145 | 21:24:47 INFO - PROCESS | 7145 | 1467865487855 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] 21:27:00 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44186/tests/dromaeo/cssquery-ext.html) 21:27:01 INFO - PROCESS | 7145 | RSS: Main: 291651584 21:27:01 INFO - PROCESS | 7145 | 21:30:04 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-ext.html (next: http://localhost:44186/tests/dromaeo/cssquery-jquery.html) 21:30:05 INFO - PROCESS | 7145 | RSS: Main: 248721408 21:30:05 INFO - PROCESS | 7145 | 21:33:07 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44186/tests/dromaeo/cssquery-mootools.html) 21:33:08 INFO - PROCESS | 7145 | RSS: Main: 244613120 21:33:08 INFO - PROCESS | 7145 | 21:36:10 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44186/tests/dromaeo/cssquery-prototype.html) 21:36:11 INFO - PROCESS | 7145 | RSS: Main: 240271360 21:36:11 INFO - PROCESS | 7145 | 21:39:14 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44186/tests/dromaeo/cssquery-yui.html) 21:39:15 INFO - PROCESS | 7145 | RSS: Main: 210841600 21:39:15 INFO - PROCESS | 7145 | 21:42:17 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:44186/tests/dromaeo/cssquery-yui.html (next: http://localhost:44186/tests/dromaeo/cssquery-dojo.html) 21:42:18 INFO - PROCESS | 7145 | RSS: Main: 271396864 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | __start_tp_report 21:42:18 INFO - PROCESS | 7145 | _x_x_mozilla_page_load 21:42:18 INFO - PROCESS | 7145 | _x_x_mozilla_page_load_details 21:42:18 INFO - PROCESS | 7145 | |i|pagename|runs| 21:42:18 INFO - PROCESS | 7145 | |0;dojo.html;2681.436898251367;2682.8122031457797;2719.3881376690247;2720.578310361892;2727.645406097207;4723.787429565669;4759.143354196243;4798.472168061515;4802.975985120078;4874.341963834881;4765.285207218919;4791.353167322411;4822.565969062793;4866.270059491078;4867.488913664068;4355.5208927018;4389.780510974454;4417.624501917341;4444.799984000728;4591.4260717410325;4500.077484115748;4525.502194758574;4546.659000574954;4547.840825571106;4589.678722489427;4324.675649326313;4362.302031674917;4363.52001279859;4383.627391671712;4433.6009759121835;4416.668749843742;4478.126765280772;4478.171538265398;4480.103979204172;4490.550944905516;4455.398521199629;4462.821487140511;4466.798994045275;4499.505054444008;4515.525869783658;4692.178868697998;4775.068861571995;4882.633802464829;4893.853184404474;4905.141600219948;5187.714675692835;5244.292020577212;5257.605679574009;5262.894742105174;5270.209468579706;5053.9241911371355;5275.76259068339;5283.973580132075;5285.180796976437;5289.338832645919;4815.951840481621;4857.95142048575;4859.562639362474;4874.366332376769;4876.536729010738;5412.242286079952;5413.323334583177;5415.945840541544;5419.214213939035;5429.837104886859;4971.5028497150715;5032.245163225545;5185.455527169668;5189.42916279209;5193.428722840485;5118.38579370478;5147.433782283946;5164.04465173944;5184.662996905189;5186.455422180692;5093.923591146136;5102.158143906288;5107.106256405114;5108.157154069611;5152.871178220575;4999.350084489066;5040.7479626019285;5086.669366491166;5093.23601459784;5101.20931255656;4329.307310830252;4348.108637729258;4388.802503887333;4391.494978077498;4399.428074350314;3802.391617341212;3816;3823.4073718573663;3858.5176852893387;3864.53625564934;4349.478062632441;4375.584319489643;4393.890152746143;4437.489688685778;4444.666650001198;5179.171332586843;5181.067407866545;5197.948020519746;5201.08980928336;5206.635535512478;4446.755428451466;4448.4439445069365;4458.152950939311;4486.214912390318;4499.325101234841;4502.5047244803045;4535.365048893158;4592.10453961481;4612.53874612536;4631.930521042187;4888.31563581106;4901.643345670487;4905.6075513958085;4912.435069966857;4955.207166853358;5159.458256883124;5175.663581867167;5179.4561571036;5188.870278243074;5227.1636538155235;4787.210110331756;4838.854834354975;4872.3909511311085;4881.414230292387;4888.682235654671;4996.475370086088;5009.799608015638;5013.949860501349;5014.623903207201;5063.949360506348;5096.694198348111;5101.183810590288;5101.9489805101475;5103.157978933583;5110.1568241239775;4830.661853670209;4869.513048695102;4873;4919.114559379346;4924.4090709115135;4866.878328041827;4872.731999740048;4918.92621610669;4924.187509060965;4927.162382394929;4843.2250839865455;4847.927281090716;4855.49017353187;4900.607951363953;4906.116898958222;4988.401391833003;4990.201567749143;4992;4998.150314446625;5045.9243111352625;4859.343988561499;4859.538343857328;4863.10032643962;4872.707637541617;4923.08922849274 21:42:18 INFO - PROCESS | 7145 | |1;ext.html;537.0871085275245;540.7512544229654;549.7828357798672;587.1369087441461;597.8057131432286;27433.58849617255;39522;40442;40477.3928391074;40524.79737601308;41675.791621041855;42010.78994605023;42175.57824421755;42209.15581688372;42280.365794513105;7411.110666720001;7588.16530181681;7738.535687858718;9978.101970822625;10315.277930544866;10191.898081019206;10293.48532573372;10294.227932905025;10295.588176472933;10295.63965261216;10328.07047365737;10361.430121343323;10376.066154046135;10376.636817711382;10376.636817711382;7619.199984001683;9975.850362244535;10225.846612300822;10243;10555.41945193014;10099.545520451597;10159.288849780518;10163.491825408773;10169.644062457852;10170.389776613352;30258.243543911358;38876.416853747214;39229.607703922884;39278.80360598179;39352.60647393518;2925;2969;3790.6019867913747;3811.8284677189595;3968.0873399118077;27905.72094279072;30212.24469388261;30527.877253180326;31110.222244443845;31755.841220793747;3586;3588.730845186595;3609.9819500902327;3625.329314076878;3637.0543658648944;260123;267494;267590;267647;267804.6609766958;47476.28785568219;48698.26952595714;49310.26034609484;50688.23967640488;51498.74250628723;61399.38600614026;61486;61526;61548.69225653843;61587;12250.081243906743;14029.579112626638;14161.291935403395;14162.71674566503;14166.716665666629;10268.075873171301;10613.0448259657;10679.519421626046;10861.456927153611;10873.565057397775;19377.797162072962;19421.543384246237;19627.116779744945;19729.901350493157;19733.309334173235;7152.17749958751;7209.531380460253;7232.674529646179;7402.666879990414;7496.475246732676;851.7615067781031;914.2822884036;959.510649568725;1006.9225928256695;1039.9116075133697;27985.440291194063;28733;29014.41971160565;29429.85285073604;29588.55617165744;910.4810258152921;931.5016466190691;941.1059493481221;1005.9144972677258;1036.8807587127426;14094.436222550983;14713.779293310608;14877.702445951238;15120.924395377953;15166.924165379323;411474;416767;416885;418290;418567;50219;50484.49515504872;50620.74689626602;50639.49360506348;50674.74662626737;1077.2459278505078;1085;1154.4631746237703;1198.0475521960063;1209.498058305811;26386.736132638427;29699.257518562208;29778;29784.702152979058;30449.391012180076;42843.785781070896;42976.57023429726;43352;43412.13175736404;43456.782716086214;257256;258324;258783;259055;259429;22468.43828904225;24706;25396.111136110147;25430.237092887244;26885.462290753683;41864.790676046425;42356.36465452959;42590.14819703527;43163.54691624725;43520.782396087816;36118.63881361153;37101.25797484089;37308.62691373051;37393;37823.243535129695;35832.64167358293;36312.4553131698;36313.636863632084;36330.455043173846;36448.63551364453;35115.82442087773;37133.442998354505;38017.61982380252;38066.80966595149;40362.39456408215;28467.857660712394;31173.688263117077;31232.843835780677;31298.530522041732;31384.215394614403;26131.73868261369;26305.342366440225;26389.23399560757;26481.205563833115;26546.336341591614 21:42:18 INFO - PROCESS | 7145 | |2;jquery.html;346.27801034842355;370.6589937257723;373.2032111442072;378.7348855800938;384.0590553144796;13207.603771886847;16454.83545164548;16479.999999999985;17150.828491715078;18098.81901180988;16231.350745970147;16342;17013.31946722133;17619.735703964452;18026.18882150303;4500.324951257305;5071.264666623347;5296.073187192245;5311.920321195185;5326.493983071602;5239.318888544484;5291.126964050927;5293.206019097147;5294.258803767476;5297.761600727977;5161.122609156434;5263.315768950031;5448.318960129984;5452.509274165324;5541.196526503654;5461.5357694596005;5497.725113744336;5511.862203444926;5514.283143191379;5517.310336207974;5481.5340696040885;5553.972230138824;5561.332640083216;5565.888682226373;5565.916511252294;11787.823182652268;11810.350430726307;11904.047676185972;12295.815562766566;12300.138990270685;1780.2522940365077;1869.3176990398542;1870.2238570993022;1872.6348362069402;1897.1368027547433;8851.380403371766;9318.487483188423;9339.90660093404;12286.938565307206;13156.473741050442;1774.8081119817016;1783.1009974377246;1820.2809890093472;1824.1973531646033;1842.5762074722754;67113;68940.6552967232;69961.65019174923;71186.64406677986;71300.28699712988;8853;9041.367104302768;9365.812683746422;9501.809963800686;10346.844797328045;11994.460249288803;12823.61529154127;12853.485860565472;13164.802527962089;13278.203307801563;7038.5073044887395;7115.501914866013;7130.786076417716;7143.4999550032035;7213.242609525924;6719.596824190564;6752.696128674222;6828.180618325832;6922.307769223138;7004.614746189009;4199.748015119103;4250.766207858597;4312.460942382202;4346.500152482442;4373.387725718402;1755.6488702259599;1766.8763186577073;1769.6991511443086;1822.1982327775736;1837.650846339218;819.7540737778762;824.1346586084587;840.0801122770556;857.9914200858036;918.4982553525035;17161.742573861404;17261.309547618206;17455.56361090983;17844.821551784575;17887.195076221342;893.2050475077186;904.8733177355175;915.4370062411568;930.6556574067638;939.5677988125402;9521.381110227812;9735.172510336559;9883.950580247196;9929.304948653526;9978.101970822516;396402;414568;414763;414860;421987;79798.60100699459;82861.17138828535;83701;83984.58007709922;84108;964.2671569607149;964.6623681711345;971.4802580619476;1002.4286156890503;1009.3641006166068;16239.51281461558;16358.591035224215;16470;16543.66912661716;16623.66752664916;29533.26166845846;38063.61936380601;38870.4169437453;38889.41665874958;39149.80425097857;50161;50186.498135018184;54311;55677.72161139168;55845.720771395885;7632.389408847168;7878.921210787818;7908.52548847071;7949.364050875801;7994.880076798969;12379.195352302071;12416.503339866304;12665.730463420667;12682.619521414372;12698.492060317853;10670.253082284165;10730.785384292427;10854.728631784268;11014.614488492864;11106.777864442827;10729.892701073202;10922.344659320466;10935.234533582572;10953.984510622035;11052.668419947415;11035.303173436667;11062.944685276521;11071.1696622752;11099.112071034458;11290.60482883128;7774.533527988338;7775.727849525239;7802.21977802238;7855.332296754611;7903.644336005009;9827.852582211415;10715.24993250465;10746.570137194425;10793.136549075898;10940.56237750513 21:42:18 INFO - PROCESS | 7145 | |3;mootools.html;1213.4357523751453;1344.67055571385;1393.519235863627;1447.4499690117755;1686.165348152664;6790.253072162058;7653.961730191349;7841.6079196040255;7866.213378662138;8184.91815081849;7796.064472263322;7941.801454963629;7956.045274567045;7991.360691144709;8000.399970002244;1935.264599452206;2152.2251989283845;2157.5792720419527;2186.0599942024924;2276.157821606008;2258.4918393361527;2272.579572779033;2278.943026424344;2280.2019293247395;2283.4291427143216;2239.428945618865;2243.45035466311;2281.840271181018;2285.565742508926;2286.325533967484;1819.2359209132196;2051.271798511522;2143.6677315016195;2144.1637761273114;2152.332776839185;2149.215536329244;2165.9241926532654;2167.5448155887284;2169.6006076081;2170.4248374180856;8034.839303213903;8080.515169089874;8091.838163236703;8095.109537950821;8097.109317975018;4369.912601747979;4483.103379324149;4490.304002879526;4491.528389519086;4504.121696269229;2770.612114303999;2880.0639792067664;2901.390707951333;2903.433830403072;2907.4039821836473;864.8400045991511;895.8163576466809;895.9148880856309;900.7164790173975;916.0473107967772;11033.83449248262;11035.282706624124;11054.778904421866;11073.5570577176;11096.167787415896;4102.179564087194;4217.219814334358;4328;4395.494518130456;4401.185780630593;9330.113639204264;9395.06049395055;9396.812063758687;9408.153266206074;9435.716928492157;4593.655475839326;4614.284785858197;4635.907281854344;4644.883877903079;4645.651576131803;3619.2761447710036;4014.6788256939376;4166.708330416903;4167.791610419527;4255.446791917031;3690.7785532868115;3730.1047748540154;3763.0780458787226;3798.9430158547643;3863.1887303666545;1072.8819829818713;1159.860816702001;1198.8081906894854;1211.987880121205;1217.391304347826;620.5283984172062;674.723363420995;744.2074190986582;755.0901164097249;761.9657115429819;5133.948660513422;5301.9926214019215;5327.920081198785;5495.917561236584;5572.777088916479;840.7982084299724;884.6505630276005;896.5830888636815;900.1763386501278;904.2585080234277;3406.625271220164;3457.4122399192197;3564.7326450516316;3633.927321453556;3692.409214525717;9785.804283914424;10148.137408320375;10173.135283500837;10181.898181018243;10184.439855808001;9926;9927.255455840841;10043.497825108716;10045.748856278651;10060.144887684482;7389.889151662729;7521.699132034767;7522.059742532184;7524.435667324972;7530.623468826537;4961.3798275215595;5160.148575485003;5182.274481572658;5229.712365819764;5245.265662807134;9329.48687822149;9444.905550944402;9461.33770636049;9488.952555237456;9535.284853636143;8838.160374764388;9206.309526785384;9215.170634642915;9538.28462865274;9548.474833884204;6103.6032657877095;6304.148939893055;6308;6820.488463365168;6993.475489338218;6893.103896493624;6903.0680858085525;7300.26997300287;7548.4716069874585;7568.243175682388;6768.9661551691925;6792.524523283323;6867.141607299088;6893.276205998298;7043.471739619651;6586.8353291168105;6618.90071648916;6723.058771771858;6808.7276508939085;6837.2820853809635;6372.58578192416;6423.550351475539;6449.838754031187;6484.1246431731115;6492.35076492347;4406.5593440655675;4451.332300154873;4460;4480.932786008278;4519.887002824956;4371.825126994884;4471.195184866755;4471.776411179493;4478.081993191453;4570.200214962302 21:42:18 INFO - PROCESS | 7145 | |4;prototype.html;297.28502950404277;298.79084640751466;299.74371912015226;308.1725566853;308.4386416721568;4839.588634966028;4874.561289483946;4880.170371036923;4886.633502487314;4898.314236006958;4759.143354196243;4813.542713442226;4816.518348165182;4837.975810120954;4842.612590992721;3641.65404286593;3755.78408738844;3767.0223132855194;3774.67915227206;3787.4508196311517;3726.813659317037;3772.6038765929593;3849.7497662651976;3850.903727406809;3873.573906870242;3781.45168950502;3789.2232092420986;3789.412641040643;3804.7717136971733;3808.1431677872533;3598;3759.6240375962457;3807.333716599584;3811.6950643948417;3828.157805282833;3263.2494526259093;3292.6378098409155;3292.983535082333;3300.1089705779546;3300.339932013607;4651.232546629802;4658.1833085096505;4669.112868554964;4699.506551812045;4755.120302744003;4113.724745328957;4244.151169766059;4250.171216612762;4284.5715428457215;4295.978520107411;4967.403911530603;4973;4982.028504441635;5002.399712034579;5002.7248501332415;3567.5897271813815;3585.748997570171;3590.8204589770407;3595.4786555949504;3620.4026335654585;5220.843374698765;5252.870632813963;5298.443663415363;5305.363356397218;5326.733663316819;3649.7773245962367;3669.1560940983723;3678.558572971207;3683.042408973686;3683.355412802749;3704.9259014819554;3713.814309284525;3714.0900479382185;3736.1033351995325;3746.363118269846;4129.905575022622;4242.745435273831;4247.766372849523;4260;4267.701260911706;5021.368055382016;5024.924626130611;5034.0686972910125;5068.859506610983;5091.6181286402925;1252.4364036183754;1288.3043156695467;1288.3171918883006;1303.178997231757;1306.2162702378687;794.4637369775379;814.2956342763586;824.1881746479713;834.4520913705111;835.1606635331574;4313.590208930146;4386.398964378043;4459.665525085632;4464.6428285737065;4464.754438505914;5181.3005244291535;5185.196294574347;5203.970451523909;5207.3751149862255;5243;4442.8222871085445;4448.377227188196;4449.310356894686;4460.286354183315;4473.530279320689;2607.8435293882426;2744.7099863064327;2755.7519823216007;2782.8469434181316;2787.6236708044153;4928.260760885824;4937.864291213041;4954.777035033432;4963.156263435225;5007.774650140679;5195.3246078009615;5270.683758974474;5352.143657014859;5356.973215133899;5381.623286369917;4922.237053256751;4922.778474968707;4940.851774446773;4950.752462376867;4963;5194.220866869849;5207.895842083213;5216.556592689512;5242.344706911636;5282.207668849704;4866;5086.923696144487;5093.516115968977;5093.7707803148205;5145.794168233228;4923.753812309442;4945.925811112764;4952.207646776643;5007.571327906036;5015.924761128659;3577.5349204603235;3592.28154369122;3640.690541304019;3650.8174591270863;3654.3604869148326;5095.363079615048;5196.428392876856;5222.477752224747;5242.213667949838;5253.290805741176;4985.775640096132;5003;5006.920743742499;5008.799648014038;5023.296738456692;4989.700617962933;5001.074801161797;5017.749112544285;5033.84898453047;5067.543921047123;4712.740799256074;4912.845481311818;4931.950680493149;4934.333864928189;4943.319271447581;4796.393208275267;4876.951230487792;5028.5862785862255;5030.8239211627415;5060.949390506195;4877.609791216623;4920.753962301942;4929.285253638274;4941.332920055746;4978.228374601942 21:42:18 INFO - PROCESS | 7145 | |5;yui.html;569.6354333226733;587.4830149468468;618.9566730328876;659.907612934189;674.6626686656672;11756;12256.81614775778;12974.54589089382;13209.273489958048;13489.73020539591;13682.93158534206;13717.451301947935;13758.587242382719;13764.518241861537;13784.104033237832;619.4146531527708;760.5208718507347;764.6100488750749;782.0380931454315;783.9216078392172;783.4476693930793;791.8060075281535;793;793.0681449297082;795.0181525815642;782.5148407987056;791.4815795653856;791.8139237279235;792.0970094092739;792.43341011177;637.6365471681144;785.8035491127218;785.8074771680917;789.388224126301;814.5357146426539;594.4263785447021;594.7085927895344;595.922530071088;596.0820336681505;596.1326270276737;11575;11588.594399196072;11593;11593.710157246052;11605;11477.540898364055;11652.76694466106;11958.64124076279;12023.269562279902;12276.570320038756;11826.231294965799;11930.940345298219;11938.522459101625;11942.940285298604;12098.274103553815;4067.3085575452246;4081.163361510883;4154.231467178552;4159.396887451333;4229.788510574459;60380;61805;62618.68690656518;63991;64021.359786402005;9009;9150.313726470542;9154.221891139328;9177.908220917705;9209.677661281823;16502.75245871313;16567.50297491077;16585.336586536643;16602.50192494227;16606;6277.52918531112;6283.465905398092;6319.905201421982;6403.551751377358;6592.17597800275;3564.144605294711;3606.6753992140834;3622.8913132606062;3677.7609455385314;3712.3874560697723;2768.68160161584;2865.412590419001;2867;2870.1246119933203;3254.23525471514;538.7414041260217;545.6535100211395;550.295621604347;550.3423409026256;561.719140429785;446.2814868062417;509.24631545313144;560.621580433206;599.8920194365056;610.3926593039865;8568.22885940256;8652.913470865211;8723;8907.821843563092;9010.414323068979;493.1370102320938;557.0947210782479;587.3715124816406;589.9380565040694;604.0908432808595;4033.092554175346;4034.3545032794614;4204.684648651363;4232.724872883253;4313.611774940207;76441.61779191068;99899;102937.48531257296;109343.45328273308;110139;49035.754821225666;72783;75073.62463187649;75135;75968.62015689997;71528.2847171532;72992.63503682447;74521.62739186377;74687.62656186793;75002;8471.745847624581;8749.956250218962;9012.63949442015;9053.094690530894;9150.908490915006;44563.554364455944;45197.77401112973;45462.31806522971;45978.54021459743;46349.304760429295;58358.7082064604;59356.40643593509;63269.68365158145;63397.36602633914;63962.68018659877;10458.895411045793;10470.685879423629;10562.894371056498;10645.520951557353;11002.834957475805;12529.561465349034;12870.67823304425;13182.80225796633;13224.272665003517;13735.725285494435;11136.276142050741;11415.315081095161;11458.427078645867;11472.139589530652;11747.29516229029;12423.751524969632;12474.75050499003;12507.187032842836;12878.162919410208;12949.158304710541;12124.636260912186;12425.316607586308;12450;13021.739565208833;13051.543195988475;9751.317407781387;10012.148967337858;10122.696319110439;10189.082982531607;10289.74275643085;9983.201343892326;10095.444750538498;10107;10224.488775561342;10264.794704106025 21:42:18 INFO - PROCESS | 7145 | __end_tp_report 21:42:18 INFO - PROCESS | 7145 | __start_cc_report 21:42:18 INFO - PROCESS | 7145 | _x_x_mozilla_cycle_collect,1288 21:42:18 INFO - PROCESS | 7145 | __end_cc_report 21:42:18 INFO - PROCESS | 7145 | __startTimestamp1467866538211__endTimestamp 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | ------- Summary: start ------- 21:42:18 INFO - PROCESS | 7145 | Number of tests: 6 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#0] dojo.html Cycles:165 Average:4761.01 Median:4872.73 stddev:497.51 (10.2%) stddev-sans-first:471.53 21:42:18 INFO - PROCESS | 7145 | Values: 2681.4 2682.8 2719.4 2720.6 2727.6 4723.8 4759.1 4798.5 4803.0 4874.3 4765.3 4791.4 4822.6 4866.3 4867.5 4355.5 4389.8 4417.6 4444.8 4591.4 4500.1 4525.5 4546.7 4547.8 4589.7 4324.7 4362.3 4363.5 4383.6 4433.6 4416.7 4478.1 4478.2 4480.1 4490.6 4455.4 4462.8 4466.8 4499.5 4515.5 4692.2 4775.1 4882.6 4893.9 4905.1 5187.7 5244.3 5257.6 5262.9 5270.2 5053.9 5275.8 5284.0 5285.2 5289.3 4816.0 4858.0 4859.6 4874.4 4876.5 5412.2 5413.3 5415.9 5419.2 5429.8 4971.5 5032.2 5185.5 5189.4 5193.4 5118.4 5147.4 5164.0 5184.7 5186.5 5093.9 5102.2 5107.1 5108.2 5152.9 4999.4 5040.7 5086.7 5093.2 5101.2 4329.3 4348.1 4388.8 4391.5 4399.4 3802.4 3816.0 3823.4 3858.5 3864.5 4349.5 4375.6 4393.9 4437.5 4444.7 5179.2 5181.1 5197.9 5201.1 5206.6 4446.8 4448.4 4458.2 4486.2 4499.3 4502.5 4535.4 4592.1 4612.5 4631.9 4888.3 4901.6 4905.6 4912.4 4955.2 5159.5 5175.7 5179.5 5188.9 5227.2 4787.2 4838.9 4872.4 4881.4 4888.7 4996.5 5009.8 5013.9 5014.6 5063.9 5096.7 5101.2 5101.9 5103.2 5110.2 4830.7 4869.5 4873.0 4919.1 4924.4 4866.9 4872.7 4918.9 4924.2 4927.2 4843.2 4847.9 4855.5 4900.6 4906.1 4988.4 4990.2 4992.0 4998.2 5045.9 4859.3 4859.5 4863.1 4872.7 4923.1 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#1] ext.html Cycles:180 Average:47592.44 Median:31570.03 stddev:84749.43 (268.4%) stddev-sans-first:84913.53 21:42:18 INFO - PROCESS | 7145 | Values: 537.1 540.8 549.8 587.1 597.8 27433.6 39522.0 40442.0 40477.4 40524.8 41675.8 42010.8 42175.6 42209.2 42280.4 7411.1 7588.2 7738.5 9978.1 10315.3 10191.9 10293.5 10294.2 10295.6 10295.6 10328.1 10361.4 10376.1 10376.6 10376.6 7619.2 9975.9 10225.8 10243.0 10555.4 10099.5 10159.3 10163.5 10169.6 10170.4 30258.2 38876.4 39229.6 39278.8 39352.6 2925.0 2969.0 3790.6 3811.8 3968.1 27905.7 30212.2 30527.9 31110.2 31755.8 3586.0 3588.7 3610.0 3625.3 3637.1 260123.0 267494.0 267590.0 267647.0 267804.7 47476.3 48698.3 49310.3 50688.2 51498.7 61399.4 61486.0 61526.0 61548.7 61587.0 12250.1 14029.6 14161.3 14162.7 14166.7 10268.1 10613.0 10679.5 10861.5 10873.6 19377.8 19421.5 19627.1 19729.9 19733.3 7152.2 7209.5 7232.7 7402.7 7496.5 851.8 914.3 959.5 1006.9 1039.9 27985.4 28733.0 29014.4 29429.9 29588.6 910.5 931.5 941.1 1005.9 1036.9 14094.4 14713.8 14877.7 15120.9 15166.9 411474.0 416767.0 416885.0 418290.0 418567.0 50219.0 50484.5 50620.7 50639.5 50674.7 1077.2 1085.0 1154.5 1198.0 1209.5 26386.7 29699.3 29778.0 29784.7 30449.4 42843.8 42976.6 43352.0 43412.1 43456.8 257256.0 258324.0 258783.0 259055.0 259429.0 22468.4 24706.0 25396.1 25430.2 26885.5 41864.8 42356.4 42590.1 43163.5 43520.8 36118.6 37101.3 37308.6 37393.0 37823.2 35832.6 36312.5 36313.6 36330.5 36448.6 35115.8 37133.4 38017.6 38066.8 40362.4 28467.9 31173.7 31232.8 31298.5 31384.2 26131.7 26305.3 26389.2 26481.2 26546.3 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#2] jquery.html Cycles:180 Average:25073.09 Median:4281.61 stddev:68163.10 (1592.0%) stddev-sans-first:68329.03 21:42:18 INFO - PROCESS | 7145 | Values: 346.3 370.7 373.2 378.7 384.1 13207.6 16454.8 16480.0 17150.8 18098.8 16231.4 16342.0 17013.3 17619.7 18026.2 4500.3 5071.3 5296.1 5311.9 5326.5 5239.3 5291.1 5293.2 5294.3 5297.8 5161.1 5263.3 5448.3 5452.5 5541.2 5461.5 5497.7 5511.9 5514.3 5517.3 5481.5 5554.0 5561.3 5565.9 5565.9 11787.8 11810.4 11904.0 12295.8 12300.1 1780.3 1869.3 1870.2 1872.6 1897.1 8851.4 9318.5 9339.9 12286.9 13156.5 1774.8 1783.1 1820.3 1824.2 1842.6 67113.0 68940.7 69961.7 71186.6 71300.3 8853.0 9041.4 9365.8 9501.8 10346.8 11994.5 12823.6 12853.5 13164.8 13278.2 7038.5 7115.5 7130.8 7143.5 7213.2 6719.6 6752.7 6828.2 6922.3 7004.6 4199.7 4250.8 4312.5 4346.5 4373.4 1755.6 1766.9 1769.7 1822.2 1837.7 819.8 824.1 840.1 858.0 918.5 17161.7 17261.3 17455.6 17844.8 17887.2 893.2 904.9 915.4 930.7 939.6 9521.4 9735.2 9884.0 9929.3 9978.1 396402.0 414568.0 414763.0 414860.0 421987.0 79798.6 82861.2 83701.0 83984.6 84108.0 964.3 964.7 971.5 1002.4 1009.4 16239.5 16358.6 16470.0 16543.7 16623.7 29533.3 38063.6 38870.4 38889.4 39149.8 50161.0 50186.5 54311.0 55677.7 55845.7 7632.4 7878.9 7908.5 7949.4 7994.9 12379.2 12416.5 12665.7 12682.6 12698.5 10670.3 10730.8 10854.7 11014.6 11106.8 10729.9 10922.3 10935.2 10954.0 11052.7 11035.3 11062.9 11071.2 11099.1 11290.6 7774.5 7775.7 7802.2 7855.3 7903.6 9827.9 10715.2 10746.6 10793.1 10940.6 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#3] mootools.html Cycles:180 Average:5202.83 Median:4645.27 stddev:2997.61 (64.5%) stddev-sans-first:2991.02 21:42:18 INFO - PROCESS | 7145 | Values: 1213.4 1344.7 1393.5 1447.4 1686.2 6790.3 7654.0 7841.6 7866.2 8184.9 7796.1 7941.8 7956.0 7991.4 8000.4 1935.3 2152.2 2157.6 2186.1 2276.2 2258.5 2272.6 2278.9 2280.2 2283.4 2239.4 2243.5 2281.8 2285.6 2286.3 1819.2 2051.3 2143.7 2144.2 2152.3 2149.2 2165.9 2167.5 2169.6 2170.4 8034.8 8080.5 8091.8 8095.1 8097.1 4369.9 4483.1 4490.3 4491.5 4504.1 2770.6 2880.1 2901.4 2903.4 2907.4 864.8 895.8 895.9 900.7 916.0 11033.8 11035.3 11054.8 11073.6 11096.2 4102.2 4217.2 4328.0 4395.5 4401.2 9330.1 9395.1 9396.8 9408.2 9435.7 4593.7 4614.3 4635.9 4644.9 4645.7 3619.3 4014.7 4166.7 4167.8 4255.4 3690.8 3730.1 3763.1 3798.9 3863.2 1072.9 1159.9 1198.8 1212.0 1217.4 620.5 674.7 744.2 755.1 762.0 5133.9 5302.0 5327.9 5495.9 5572.8 840.8 884.7 896.6 900.2 904.3 3406.6 3457.4 3564.7 3633.9 3692.4 9785.8 10148.1 10173.1 10181.9 10184.4 9926.0 9927.3 10043.5 10045.7 10060.1 7389.9 7521.7 7522.1 7524.4 7530.6 4961.4 5160.1 5182.3 5229.7 5245.3 9329.5 9444.9 9461.3 9489.0 9535.3 8838.2 9206.3 9215.2 9538.3 9548.5 6103.6 6304.1 6308.0 6820.5 6993.5 6893.1 6903.1 7300.3 7548.5 7568.2 6769.0 6792.5 6867.1 6893.3 7043.5 6586.8 6618.9 6723.1 6808.7 6837.3 6372.6 6423.6 6449.8 6484.1 6492.4 4406.6 4451.3 4460.0 4480.9 4519.9 4371.8 4471.2 4471.8 4478.1 4570.2 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#4] prototype.html Cycles:180 Average:4190.76 Median:4838.78 stddev:1221.42 (25.2%) stddev-sans-first:1189.37 21:42:18 INFO - PROCESS | 7145 | Values: 297.3 298.8 299.7 308.2 308.4 4839.6 4874.6 4880.2 4886.6 4898.3 4759.1 4813.5 4816.5 4838.0 4842.6 3641.7 3755.8 3767.0 3774.7 3787.5 3726.8 3772.6 3849.7 3850.9 3873.6 3781.5 3789.2 3789.4 3804.8 3808.1 3598.0 3759.6 3807.3 3811.7 3828.2 3263.2 3292.6 3293.0 3300.1 3300.3 4651.2 4658.2 4669.1 4699.5 4755.1 4113.7 4244.2 4250.2 4284.6 4296.0 4967.4 4973.0 4982.0 5002.4 5002.7 3567.6 3585.7 3590.8 3595.5 3620.4 5220.8 5252.9 5298.4 5305.4 5326.7 3649.8 3669.2 3678.6 3683.0 3683.4 3704.9 3713.8 3714.1 3736.1 3746.4 4129.9 4242.7 4247.8 4260.0 4267.7 5021.4 5024.9 5034.1 5068.9 5091.6 1252.4 1288.3 1288.3 1303.2 1306.2 794.5 814.3 824.2 834.5 835.2 4313.6 4386.4 4459.7 4464.6 4464.8 5181.3 5185.2 5204.0 5207.4 5243.0 4442.8 4448.4 4449.3 4460.3 4473.5 2607.8 2744.7 2755.8 2782.8 2787.6 4928.3 4937.9 4954.8 4963.2 5007.8 5195.3 5270.7 5352.1 5357.0 5381.6 4922.2 4922.8 4940.9 4950.8 4963.0 5194.2 5207.9 5216.6 5242.3 5282.2 4866.0 5086.9 5093.5 5093.8 5145.8 4923.8 4945.9 4952.2 5007.6 5015.9 3577.5 3592.3 3640.7 3650.8 3654.4 5095.4 5196.4 5222.5 5242.2 5253.3 4985.8 5003.0 5006.9 5008.8 5023.3 4989.7 5001.1 5017.7 5033.8 5067.5 4712.7 4912.8 4932.0 4934.3 4943.3 4796.4 4877.0 5028.6 5030.8 5060.9 4877.6 4920.8 4929.3 4941.3 4978.2 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - PROCESS | 7145 | [#5] yui.html Cycles:180 Average:17387.89 Median:46163.92 stddev:24636.30 (53.4%) stddev-sans-first:24673.05 21:42:18 INFO - PROCESS | 7145 | Values: 569.6 587.5 619.0 659.9 674.7 11756.0 12256.8 12974.5 13209.3 13489.7 13682.9 13717.5 13758.6 13764.5 13784.1 619.4 760.5 764.6 782.0 783.9 783.4 791.8 793.0 793.1 795.0 782.5 791.5 791.8 792.1 792.4 637.6 785.8 785.8 789.4 814.5 594.4 594.7 595.9 596.1 596.1 11575.0 11588.6 11593.0 11593.7 11605.0 11477.5 11652.8 11958.6 12023.3 12276.6 11826.2 11930.9 11938.5 11942.9 12098.3 4067.3 4081.2 4154.2 4159.4 4229.8 60380.0 61805.0 62618.7 63991.0 64021.4 9009.0 9150.3 9154.2 9177.9 9209.7 16502.8 16567.5 16585.3 16602.5 16606.0 6277.5 6283.5 6319.9 6403.6 6592.2 3564.1 3606.7 3622.9 3677.8 3712.4 2768.7 2865.4 2867.0 2870.1 3254.2 538.7 545.7 550.3 550.3 561.7 446.3 509.2 560.6 599.9 610.4 8568.2 8652.9 8723.0 8907.8 9010.4 493.1 557.1 587.4 589.9 604.1 4033.1 4034.4 4204.7 4232.7 4313.6 76441.6 99899.0 102937.5 109343.5 110139.0 49035.8 72783.0 75073.6 75135.0 75968.6 71528.3 72992.6 74521.6 74687.6 75002.0 8471.7 8750.0 9012.6 9053.1 9150.9 44563.6 45197.8 45462.3 45978.5 46349.3 58358.7 59356.4 63269.7 63397.4 63962.7 10458.9 10470.7 10562.9 10645.5 11002.8 12529.6 12870.7 13182.8 13224.3 13735.7 11136.3 11415.3 11458.4 11472.1 11747.3 12423.8 12474.8 12507.2 12878.2 12949.2 12124.6 12425.3 12450.0 13021.7 13051.5 9751.3 10012.1 10122.7 10189.1 10289.7 9983.2 10095.4 10107.0 10224.5 10264.8 21:42:18 INFO - PROCESS | 7145 | -------- Summary: end -------- 21:42:18 INFO - PROCESS | 7145 | 21:42:18 INFO - TEST-INFO | 7145: exit 0 21:42:18 INFO - TEST-OK | dromaeo_css | took 1120727ms 21:42:18 INFO - TEST-START | kraken 21:42:18 INFO - operating with platform_type : linux_ 21:42:18 INFO - Initialising browser for kraken test... 21:42:18 INFO - TEST-INFO | started process 7331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5_tnvU/profile http://localhost:44186/getInfo.html) 21:42:23 INFO - PROCESS | 7331 | __metrics Screen width/height:1600/1200 21:42:23 INFO - PROCESS | 7331 | colorDepth:24 21:42:23 INFO - PROCESS | 7331 | Browser inner width/height: 1024/697 21:42:23 INFO - PROCESS | 7331 | __metrics 21:42:24 INFO - PROCESS | 7331 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:42:25 INFO - TEST-INFO | 7331: exit 0 21:42:25 INFO - Browser initialized. 21:42:25 INFO - Running cycle 1/1 for kraken test... 21:42:25 INFO - TEST-INFO | started process 7415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5_tnvU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:42:37 INFO - PROCESS | 7415 | RSS: Main: 159010816 21:42:37 INFO - PROCESS | 7415 | 21:42:39 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44186/tests/kraken/driver.html?testName=audio-beat-detection) 21:42:40 INFO - PROCESS | 7415 | RSS: Main: 217014272 21:42:40 INFO - PROCESS | 7415 | 21:42:43 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44186/tests/kraken/driver.html?testName=audio-dft) 21:42:43 INFO - PROCESS | 7415 | RSS: Main: 204513280 21:42:43 INFO - PROCESS | 7415 | 21:42:47 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44186/tests/kraken/driver.html?testName=audio-fft) 21:42:47 INFO - PROCESS | 7415 | RSS: Main: 271921152 21:42:47 INFO - PROCESS | 7415 | 21:42:50 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44186/tests/kraken/driver.html?testName=audio-oscillator) 21:42:50 INFO - PROCESS | 7415 | RSS: Main: 182366208 21:42:50 INFO - PROCESS | 7415 | 21:42:52 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44186/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:42:53 INFO - PROCESS | 7415 | RSS: Main: 186085376 21:42:53 INFO - PROCESS | 7415 | 21:42:56 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44186/tests/kraken/driver.html?testName=imaging-darkroom) 21:42:57 INFO - PROCESS | 7415 | RSS: Main: 222023680 21:42:57 INFO - PROCESS | 7415 | 21:43:00 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44186/tests/kraken/driver.html?testName=imaging-desaturate) 21:43:01 INFO - PROCESS | 7415 | RSS: Main: 222121984 21:43:01 INFO - PROCESS | 7415 | 21:43:04 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44186/tests/kraken/driver.html?testName=json-parse-financial) 21:43:04 INFO - PROCESS | 7415 | RSS: Main: 222445568 21:43:04 INFO - PROCESS | 7415 | 21:43:06 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44186/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:43:07 INFO - PROCESS | 7415 | RSS: Main: 208457728 21:43:07 INFO - PROCESS | 7415 | 21:43:08 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:43:09 INFO - PROCESS | 7415 | RSS: Main: 212652032 21:43:09 INFO - PROCESS | 7415 | 21:43:11 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:43:12 INFO - PROCESS | 7415 | RSS: Main: 216596480 21:43:12 INFO - PROCESS | 7415 | 21:43:15 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:43:15 INFO - PROCESS | 7415 | RSS: Main: 226439168 21:43:15 INFO - PROCESS | 7415 | 21:43:18 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:43:19 INFO - PROCESS | 7415 | RSS: Main: 228667392 21:43:19 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | Cycle 1(1): loaded http://localhost:44186/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44186/tests/kraken/driver.html?testName=ai-astar) 21:43:21 INFO - PROCESS | 7415 | RSS: Main: 270520320 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | __start_tp_report 21:43:21 INFO - PROCESS | 7415 | _x_x_mozilla_page_load 21:43:21 INFO - PROCESS | 7415 | _x_x_mozilla_page_load_details 21:43:21 INFO - PROCESS | 7415 | |i|pagename|runs| 21:43:21 INFO - PROCESS | 7415 | |0;ai-astar;102;113;114;112;112;113;112;111;114;111 21:43:21 INFO - PROCESS | 7415 | |1;audio-beat-detection;146;123;124;171;121;126;146;127;175;125 21:43:21 INFO - PROCESS | 7415 | |2;audio-dft;162;161;161;160;162;161;163;161;161;159 21:43:21 INFO - PROCESS | 7415 | |3;audio-fft;75;77;75;127;76;77;76;75;107;75 21:43:21 INFO - PROCESS | 7415 | |4;audio-oscillator;85;84;111;85;87;86;117;85;85;125 21:43:21 INFO - PROCESS | 7415 | |5;imaging-gaussian-blur;116;117;117;117;118;117;116;117;117;117 21:43:21 INFO - PROCESS | 7415 | |6;imaging-darkroom;146;146;147;147;146;146;146;146;146;146 21:43:21 INFO - PROCESS | 7415 | |7;imaging-desaturate;95;95;95;95;97;96;96;96;96;95 21:43:21 INFO - PROCESS | 7415 | |8;json-parse-financial;73;64;63;65;64;63;64;63;66;64 21:43:21 INFO - PROCESS | 7415 | |9;json-stringify-tinderbox;65;64;65;64;64;64;64;65;66;66 21:43:21 INFO - PROCESS | 7415 | |10;stanford-crypto-aes;87;82;89;77;83;87;86;85;79;87 21:43:21 INFO - PROCESS | 7415 | |11;stanford-crypto-ccm;128;127;138;128;146;127;129;129;166;145 21:43:21 INFO - PROCESS | 7415 | |12;stanford-crypto-pbkdf2;162;166;165;166;165;162;167;161;162;169 21:43:21 INFO - PROCESS | 7415 | |13;stanford-crypto-sha256-iterative;74;67;69;67;66;67;66;66;69;64 21:43:21 INFO - PROCESS | 7415 | __end_tp_report 21:43:21 INFO - PROCESS | 7415 | __start_cc_report 21:43:21 INFO - PROCESS | 7415 | _x_x_mozilla_cycle_collect,1941 21:43:21 INFO - PROCESS | 7415 | __end_cc_report 21:43:21 INFO - PROCESS | 7415 | __startTimestamp1467866601262__endTimestamp 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | ------- Summary: start ------- 21:43:21 INFO - PROCESS | 7415 | Number of tests: 14 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:3.47 (3.1%) stddev-sans-first:1.13 21:43:21 INFO - PROCESS | 7415 | Values: 102.0 113.0 114.0 112.0 112.0 113.0 112.0 111.0 114.0 111.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:136.50 stddev:20.35 (14.9%) stddev-sans-first:21.40 21:43:21 INFO - PROCESS | 7415 | Values: 146.0 123.0 124.0 171.0 121.0 126.0 146.0 127.0 175.0 125.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 21:43:21 INFO - PROCESS | 7415 | Values: 162.0 161.0 161.0 160.0 162.0 161.0 163.0 161.0 161.0 159.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#3] audio-fft Cycles:10 Average:84.00 Median:75.50 stddev:18.04 (23.9%) stddev-sans-first:18.83 21:43:21 INFO - PROCESS | 7415 | Values: 75.0 77.0 75.0 127.0 76.0 77.0 76.0 75.0 107.0 75.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:85.00 stddev:16.01 (18.8%) stddev-sans-first:16.56 21:43:21 INFO - PROCESS | 7415 | Values: 85.0 84.0 111.0 85.0 87.0 86.0 117.0 85.0 85.0 125.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 21:43:21 INFO - PROCESS | 7415 | Values: 116.0 117.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 117.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 21:43:21 INFO - PROCESS | 7415 | Values: 146.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 21:43:21 INFO - PROCESS | 7415 | Values: 95.0 95.0 95.0 95.0 97.0 96.0 96.0 96.0 96.0 95.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:3.00 (4.7%) stddev-sans-first:1.00 21:43:21 INFO - PROCESS | 7415 | Values: 73.0 64.0 63.0 65.0 64.0 63.0 64.0 63.0 66.0 64.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 21:43:21 INFO - PROCESS | 7415 | Values: 65.0 64.0 65.0 64.0 64.0 64.0 64.0 65.0 66.0 66.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:86.50 stddev:3.88 (4.5%) stddev-sans-first:3.98 21:43:21 INFO - PROCESS | 7415 | Values: 87.0 82.0 89.0 77.0 83.0 87.0 86.0 85.0 79.0 87.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:133.50 stddev:12.79 (9.6%) stddev-sans-first:13.21 21:43:21 INFO - PROCESS | 7415 | Values: 128.0 127.0 138.0 128.0 146.0 127.0 129.0 129.0 166.0 145.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:165.50 stddev:2.64 (1.6%) stddev-sans-first:2.64 21:43:21 INFO - PROCESS | 7415 | Values: 162.0 166.0 165.0 166.0 165.0 162.0 167.0 161.0 162.0 169.0 21:43:21 INFO - PROCESS | 7415 | 21:43:21 INFO - PROCESS | 7415 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.72 (4.1%) stddev-sans-first:1.56 21:43:21 INFO - PROCESS | 7415 | Values: 74.0 67.0 69.0 67.0 66.0 67.0 66.0 66.0 69.0 64.0 21:43:21 INFO - PROCESS | 7415 | -------- Summary: end -------- 21:43:21 INFO - PROCESS | 7415 | 21:43:22 INFO - TEST-INFO | 7415: exit 0 21:43:22 INFO - TEST-OK | kraken | took 63356ms 21:43:22 INFO - SUITE-END | took 1184s 21:43:22 INFO - Completed test suite (00:19:44) 21:43:22 INFO - javascript benchmark 21:43:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2681.436898251367, 2682.8122031457797, 2719.3881376690247, 2720.578310361892, 2727.645406097207, 4723.787429565669, 4759.143354196243, 4798.472168061515, 4802.975985120078, 4874.341963834881, 4765.285207218919, 4791.353167322411, 4822.565969062793, 4866.270059491078, 4867.488913664068, 4355.5208927018, 4389.780510974454, 4417.624501917341, 4444.799984000728, 4591.4260717410325, 4500.077484115748, 4525.502194758574, 4546.659000574954, 4547.840825571106, 4589.678722489427, 4324.675649326313, 4362.302031674917, 4363.52001279859, 4383.627391671712, 4433.6009759121835, 4416.668749843742, 4478.126765280772, 4478.171538265398, 4480.103979204172, 4490.550944905516, 4455.398521199629, 4462.821487140511, 4466.798994045275, 4499.505054444008, 4515.525869783658, 4692.178868697998, 4775.068861571995, 4882.633802464829, 4893.853184404474, 4905.141600219948, 5187.714675692835, 5244.292020577212, 5257.605679574009, 5262.894742105174, 5270.209468579706, 5053.9241911371355, 5275.76259068339, 5283.973580132075, 5285.180796976437, 5289.338832645919, 4815.951840481621, 4857.95142048575, 4859.562639362474, 4874.366332376769, 4876.536729010738, 5412.242286079952, 5413.323334583177, 5415.945840541544, 5419.214213939035, 5429.837104886859, 4971.5028497150715, 5032.245163225545, 5185.455527169668, 5189.42916279209, 5193.428722840485, 5118.38579370478, 5147.433782283946, 5164.04465173944, 5184.662996905189, 5186.455422180692, 5093.923591146136, 5102.158143906288, 5107.106256405114, 5108.157154069611, 5152.871178220575, 4999.350084489066, 5040.7479626019285, 5086.669366491166, 5093.23601459784, 5101.20931255656, 4329.307310830252, 4348.108637729258, 4388.802503887333, 4391.494978077498, 4399.428074350314, 3802.391617341212, 3816.0, 3823.4073718573663, 3858.5176852893387, 3864.53625564934, 4349.478062632441, 4375.584319489643, 4393.890152746143, 4437.489688685778, 4444.666650001198, 5179.171332586843, 5181.067407866545, 5197.948020519746, 5201.08980928336, 5206.635535512478, 4446.755428451466, 4448.4439445069365, 4458.152950939311, 4486.214912390318, 4499.325101234841, 4502.5047244803045, 4535.365048893158, 4592.10453961481, 4612.53874612536, 4631.930521042187, 4888.31563581106, 4901.643345670487, 4905.6075513958085, 4912.435069966857, 4955.207166853358, 5159.458256883124, 5175.663581867167, 5179.4561571036, 5188.870278243074, 5227.1636538155235, 4787.210110331756, 4838.854834354975, 4872.3909511311085, 4881.414230292387, 4888.682235654671, 4996.475370086088, 5009.799608015638, 5013.949860501349, 5014.623903207201, 5063.949360506348, 5096.694198348111, 5101.183810590288, 5101.9489805101475, 5103.157978933583, 5110.1568241239775, 4830.661853670209, 4869.513048695102, 4873.0, 4919.114559379346, 4924.4090709115135, 4866.878328041827, 4872.731999740048, 4918.92621610669, 4924.187509060965, 4927.162382394929, 4843.2250839865455, 4847.927281090716, 4855.49017353187, 4900.607951363953, 4906.116898958222, 4988.401391833003, 4990.201567749143, 4992.0, 4998.150314446625, 5045.9243111352625, 4859.343988561499, 4859.538343857328, 4863.10032643962, 4872.707637541617, 4923.08922849274], "value": 4729.4926107032525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.0871085275245, 540.7512544229654, 549.7828357798672, 587.1369087441461, 597.8057131432286, 27433.58849617255, 39522.0, 40442.0, 40477.3928391074, 40524.79737601308, 41675.791621041855, 42010.78994605023, 42175.57824421755, 42209.15581688372, 42280.365794513105, 7411.110666720001, 7588.16530181681, 7738.535687858718, 9978.101970822625, 10315.277930544866, 10191.898081019206, 10293.48532573372, 10294.227932905025, 10295.588176472933, 10295.63965261216, 10328.07047365737, 10361.430121343323, 10376.066154046135, 10376.636817711382, 10376.636817711382, 7619.199984001683, 9975.850362244535, 10225.846612300822, 10243.0, 10555.41945193014, 10099.545520451597, 10159.288849780518, 10163.491825408773, 10169.644062457852, 10170.389776613352, 30258.243543911358, 38876.416853747214, 39229.607703922884, 39278.80360598179, 39352.60647393518, 2925.0, 2969.0, 3790.6019867913747, 3811.8284677189595, 3968.0873399118077, 27905.72094279072, 30212.24469388261, 30527.877253180326, 31110.222244443845, 31755.841220793747, 3586.0, 3588.730845186595, 3609.9819500902327, 3625.329314076878, 3637.0543658648944, 260123.0, 267494.0, 267590.0, 267647.0, 267804.6609766958, 47476.28785568219, 48698.26952595714, 49310.26034609484, 50688.23967640488, 51498.74250628723, 61399.38600614026, 61486.0, 61526.0, 61548.69225653843, 61587.0, 12250.081243906743, 14029.579112626638, 14161.291935403395, 14162.71674566503, 14166.716665666629, 10268.075873171301, 10613.0448259657, 10679.519421626046, 10861.456927153611, 10873.565057397775, 19377.797162072962, 19421.543384246237, 19627.116779744945, 19729.901350493157, 19733.309334173235, 7152.17749958751, 7209.531380460253, 7232.674529646179, 7402.666879990414, 7496.475246732676, 851.7615067781031, 914.2822884036, 959.510649568725, 1006.9225928256695, 1039.9116075133697, 27985.440291194063, 28733.0, 29014.41971160565, 29429.85285073604, 29588.55617165744, 910.4810258152921, 931.5016466190691, 941.1059493481221, 1005.9144972677258, 1036.8807587127426, 14094.436222550983, 14713.779293310608, 14877.702445951238, 15120.924395377953, 15166.924165379323, 411474.0, 416767.0, 416885.0, 418290.0, 418567.0, 50219.0, 50484.49515504872, 50620.74689626602, 50639.49360506348, 50674.74662626737, 1077.2459278505078, 1085.0, 1154.4631746237703, 1198.0475521960063, 1209.498058305811, 26386.736132638427, 29699.257518562208, 29778.0, 29784.702152979058, 30449.391012180076, 42843.785781070896, 42976.57023429726, 43352.0, 43412.13175736404, 43456.782716086214, 257256.0, 258324.0, 258783.0, 259055.0, 259429.0, 22468.43828904225, 24706.0, 25396.111136110147, 25430.237092887244, 26885.462290753683, 41864.790676046425, 42356.36465452959, 42590.14819703527, 43163.54691624725, 43520.782396087816, 36118.63881361153, 37101.25797484089, 37308.62691373051, 37393.0, 37823.243535129695, 35832.64167358293, 36312.4553131698, 36313.636863632084, 36330.455043173846, 36448.63551364453, 35115.82442087773, 37133.442998354505, 38017.61982380252, 38066.80966595149, 40362.39456408215, 28467.857660712394, 31173.688263117077, 31232.843835780677, 31298.530522041732, 31384.215394614403, 26131.73868261369, 26305.342366440225, 26389.23399560757, 26481.205563833115, 26546.336341591614], "value": 18276.303684722217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.27801034842355, 370.6589937257723, 373.2032111442072, 378.7348855800938, 384.0590553144796, 13207.603771886847, 16454.83545164548, 16479.999999999985, 17150.828491715078, 18098.81901180988, 16231.350745970147, 16342.0, 17013.31946722133, 17619.735703964452, 18026.18882150303, 4500.324951257305, 5071.264666623347, 5296.073187192245, 5311.920321195185, 5326.493983071602, 5239.318888544484, 5291.126964050927, 5293.206019097147, 5294.258803767476, 5297.761600727977, 5161.122609156434, 5263.315768950031, 5448.318960129984, 5452.509274165324, 5541.196526503654, 5461.5357694596005, 5497.725113744336, 5511.862203444926, 5514.283143191379, 5517.310336207974, 5481.5340696040885, 5553.972230138824, 5561.332640083216, 5565.888682226373, 5565.916511252294, 11787.823182652268, 11810.350430726307, 11904.047676185972, 12295.815562766566, 12300.138990270685, 1780.2522940365077, 1869.3176990398542, 1870.2238570993022, 1872.6348362069402, 1897.1368027547433, 8851.380403371766, 9318.487483188423, 9339.90660093404, 12286.938565307206, 13156.473741050442, 1774.8081119817016, 1783.1009974377246, 1820.2809890093472, 1824.1973531646033, 1842.5762074722754, 67113.0, 68940.6552967232, 69961.65019174923, 71186.64406677986, 71300.28699712988, 8853.0, 9041.367104302768, 9365.812683746422, 9501.809963800686, 10346.844797328045, 11994.460249288803, 12823.61529154127, 12853.485860565472, 13164.802527962089, 13278.203307801563, 7038.5073044887395, 7115.501914866013, 7130.786076417716, 7143.4999550032035, 7213.242609525924, 6719.596824190564, 6752.696128674222, 6828.180618325832, 6922.307769223138, 7004.614746189009, 4199.748015119103, 4250.766207858597, 4312.460942382202, 4346.500152482442, 4373.387725718402, 1755.6488702259599, 1766.8763186577073, 1769.6991511443086, 1822.1982327775736, 1837.650846339218, 819.7540737778762, 824.1346586084587, 840.0801122770556, 857.9914200858036, 918.4982553525035, 17161.742573861404, 17261.309547618206, 17455.56361090983, 17844.821551784575, 17887.195076221342, 893.2050475077186, 904.8733177355175, 915.4370062411568, 930.6556574067638, 939.5677988125402, 9521.381110227812, 9735.172510336559, 9883.950580247196, 9929.304948653526, 9978.101970822516, 396402.0, 414568.0, 414763.0, 414860.0, 421987.0, 79798.60100699459, 82861.17138828535, 83701.0, 83984.58007709922, 84108.0, 964.2671569607149, 964.6623681711345, 971.4802580619476, 1002.4286156890503, 1009.3641006166068, 16239.51281461558, 16358.591035224215, 16470.0, 16543.66912661716, 16623.66752664916, 29533.26166845846, 38063.61936380601, 38870.4169437453, 38889.41665874958, 39149.80425097857, 50161.0, 50186.498135018184, 54311.0, 55677.72161139168, 55845.720771395885, 7632.389408847168, 7878.921210787818, 7908.52548847071, 7949.364050875801, 7994.880076798969, 12379.195352302071, 12416.503339866304, 12665.730463420667, 12682.619521414372, 12698.492060317853, 10670.253082284165, 10730.785384292427, 10854.728631784268, 11014.614488492864, 11106.777864442827, 10729.892701073202, 10922.344659320466, 10935.234533582572, 10953.984510622035, 11052.668419947415, 11035.303173436667, 11062.944685276521, 11071.1696622752, 11099.112071034458, 11290.60482883128, 7774.533527988338, 7775.727849525239, 7802.21977802238, 7855.332296754611, 7903.644336005009, 9827.852582211415, 10715.24993250465, 10746.570137194425, 10793.136549075898, 10940.56237750513], "value": 8248.401016615102, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.4357523751453, 1344.67055571385, 1393.519235863627, 1447.4499690117755, 1686.165348152664, 6790.253072162058, 7653.961730191349, 7841.6079196040255, 7866.213378662138, 8184.91815081849, 7796.064472263322, 7941.801454963629, 7956.045274567045, 7991.360691144709, 8000.399970002244, 1935.264599452206, 2152.2251989283845, 2157.5792720419527, 2186.0599942024924, 2276.157821606008, 2258.4918393361527, 2272.579572779033, 2278.943026424344, 2280.2019293247395, 2283.4291427143216, 2239.428945618865, 2243.45035466311, 2281.840271181018, 2285.565742508926, 2286.325533967484, 1819.2359209132196, 2051.271798511522, 2143.6677315016195, 2144.1637761273114, 2152.332776839185, 2149.215536329244, 2165.9241926532654, 2167.5448155887284, 2169.6006076081, 2170.4248374180856, 8034.839303213903, 8080.515169089874, 8091.838163236703, 8095.109537950821, 8097.109317975018, 4369.912601747979, 4483.103379324149, 4490.304002879526, 4491.528389519086, 4504.121696269229, 2770.612114303999, 2880.0639792067664, 2901.390707951333, 2903.433830403072, 2907.4039821836473, 864.8400045991511, 895.8163576466809, 895.9148880856309, 900.7164790173975, 916.0473107967772, 11033.83449248262, 11035.282706624124, 11054.778904421866, 11073.5570577176, 11096.167787415896, 4102.179564087194, 4217.219814334358, 4328.0, 4395.494518130456, 4401.185780630593, 9330.113639204264, 9395.06049395055, 9396.812063758687, 9408.153266206074, 9435.716928492157, 4593.655475839326, 4614.284785858197, 4635.907281854344, 4644.883877903079, 4645.651576131803, 3619.2761447710036, 4014.6788256939376, 4166.708330416903, 4167.791610419527, 4255.446791917031, 3690.7785532868115, 3730.1047748540154, 3763.0780458787226, 3798.9430158547643, 3863.1887303666545, 1072.8819829818713, 1159.860816702001, 1198.8081906894854, 1211.987880121205, 1217.391304347826, 620.5283984172062, 674.723363420995, 744.2074190986582, 755.0901164097249, 761.9657115429819, 5133.948660513422, 5301.9926214019215, 5327.920081198785, 5495.917561236584, 5572.777088916479, 840.7982084299724, 884.6505630276005, 896.5830888636815, 900.1763386501278, 904.2585080234277, 3406.625271220164, 3457.4122399192197, 3564.7326450516316, 3633.927321453556, 3692.409214525717, 9785.804283914424, 10148.137408320375, 10173.135283500837, 10181.898181018243, 10184.439855808001, 9926.0, 9927.255455840841, 10043.497825108716, 10045.748856278651, 10060.144887684482, 7389.889151662729, 7521.699132034767, 7522.059742532184, 7524.435667324972, 7530.623468826537, 4961.3798275215595, 5160.148575485003, 5182.274481572658, 5229.712365819764, 5245.265662807134, 9329.48687822149, 9444.905550944402, 9461.33770636049, 9488.952555237456, 9535.284853636143, 8838.160374764388, 9206.309526785384, 9215.170634642915, 9538.28462865274, 9548.474833884204, 6103.6032657877095, 6304.148939893055, 6308.0, 6820.488463365168, 6993.475489338218, 6893.103896493624, 6903.0680858085525, 7300.26997300287, 7548.4716069874585, 7568.243175682388, 6768.9661551691925, 6792.524523283323, 6867.141607299088, 6893.276205998298, 7043.471739619651, 6586.8353291168105, 6618.90071648916, 6723.058771771858, 6808.7276508939085, 6837.2820853809635, 6372.58578192416, 6423.550351475539, 6449.838754031187, 6484.1246431731115, 6492.35076492347, 4406.5593440655675, 4451.332300154873, 4460.0, 4480.932786008278, 4519.887002824956, 4371.825126994884, 4471.195184866755, 4471.776411179493, 4478.081993191453, 4570.200214962302], "value": 4143.079363630222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.28502950404277, 298.79084640751466, 299.74371912015226, 308.1725566853, 308.4386416721568, 4839.588634966028, 4874.561289483946, 4880.170371036923, 4886.633502487314, 4898.314236006958, 4759.143354196243, 4813.542713442226, 4816.518348165182, 4837.975810120954, 4842.612590992721, 3641.65404286593, 3755.78408738844, 3767.0223132855194, 3774.67915227206, 3787.4508196311517, 3726.813659317037, 3772.6038765929593, 3849.7497662651976, 3850.903727406809, 3873.573906870242, 3781.45168950502, 3789.2232092420986, 3789.412641040643, 3804.7717136971733, 3808.1431677872533, 3598.0, 3759.6240375962457, 3807.333716599584, 3811.6950643948417, 3828.157805282833, 3263.2494526259093, 3292.6378098409155, 3292.983535082333, 3300.1089705779546, 3300.339932013607, 4651.232546629802, 4658.1833085096505, 4669.112868554964, 4699.506551812045, 4755.120302744003, 4113.724745328957, 4244.151169766059, 4250.171216612762, 4284.5715428457215, 4295.978520107411, 4967.403911530603, 4973.0, 4982.028504441635, 5002.399712034579, 5002.7248501332415, 3567.5897271813815, 3585.748997570171, 3590.8204589770407, 3595.4786555949504, 3620.4026335654585, 5220.843374698765, 5252.870632813963, 5298.443663415363, 5305.363356397218, 5326.733663316819, 3649.7773245962367, 3669.1560940983723, 3678.558572971207, 3683.042408973686, 3683.355412802749, 3704.9259014819554, 3713.814309284525, 3714.0900479382185, 3736.1033351995325, 3746.363118269846, 4129.905575022622, 4242.745435273831, 4247.766372849523, 4260.0, 4267.701260911706, 5021.368055382016, 5024.924626130611, 5034.0686972910125, 5068.859506610983, 5091.6181286402925, 1252.4364036183754, 1288.3043156695467, 1288.3171918883006, 1303.178997231757, 1306.2162702378687, 794.4637369775379, 814.2956342763586, 824.1881746479713, 834.4520913705111, 835.1606635331574, 4313.590208930146, 4386.398964378043, 4459.665525085632, 4464.6428285737065, 4464.754438505914, 5181.3005244291535, 5185.196294574347, 5203.970451523909, 5207.3751149862255, 5243.0, 4442.8222871085445, 4448.377227188196, 4449.310356894686, 4460.286354183315, 4473.530279320689, 2607.8435293882426, 2744.7099863064327, 2755.7519823216007, 2782.8469434181316, 2787.6236708044153, 4928.260760885824, 4937.864291213041, 4954.777035033432, 4963.156263435225, 5007.774650140679, 5195.3246078009615, 5270.683758974474, 5352.143657014859, 5356.973215133899, 5381.623286369917, 4922.237053256751, 4922.778474968707, 4940.851774446773, 4950.752462376867, 4963.0, 5194.220866869849, 5207.895842083213, 5216.556592689512, 5242.344706911636, 5282.207668849704, 4866.0, 5086.923696144487, 5093.516115968977, 5093.7707803148205, 5145.794168233228, 4923.753812309442, 4945.925811112764, 4952.207646776643, 5007.571327906036, 5015.924761128659, 3577.5349204603235, 3592.28154369122, 3640.690541304019, 3650.8174591270863, 3654.3604869148326, 5095.363079615048, 5196.428392876856, 5222.477752224747, 5242.213667949838, 5253.290805741176, 4985.775640096132, 5003.0, 5006.920743742499, 5008.799648014038, 5023.296738456692, 4989.700617962933, 5001.074801161797, 5017.749112544285, 5033.84898453047, 5067.543921047123, 4712.740799256074, 4912.845481311818, 4931.950680493149, 4934.333864928189, 4943.319271447581, 4796.393208275267, 4876.951230487792, 5028.5862785862255, 5030.8239211627415, 5060.949390506195, 4877.609791216623, 4920.753962301942, 4929.285253638274, 4941.332920055746, 4978.228374601942], "value": 3800.7722466603454, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.6354333226733, 587.4830149468468, 618.9566730328876, 659.907612934189, 674.6626686656672, 11756.0, 12256.81614775778, 12974.54589089382, 13209.273489958048, 13489.73020539591, 13682.93158534206, 13717.451301947935, 13758.587242382719, 13764.518241861537, 13784.104033237832, 619.4146531527708, 760.5208718507347, 764.6100488750749, 782.0380931454315, 783.9216078392172, 783.4476693930793, 791.8060075281535, 793.0, 793.0681449297082, 795.0181525815642, 782.5148407987056, 791.4815795653856, 791.8139237279235, 792.0970094092739, 792.43341011177, 637.6365471681144, 785.8035491127218, 785.8074771680917, 789.388224126301, 814.5357146426539, 594.4263785447021, 594.7085927895344, 595.922530071088, 596.0820336681505, 596.1326270276737, 11575.0, 11588.594399196072, 11593.0, 11593.710157246052, 11605.0, 11477.540898364055, 11652.76694466106, 11958.64124076279, 12023.269562279902, 12276.570320038756, 11826.231294965799, 11930.940345298219, 11938.522459101625, 11942.940285298604, 12098.274103553815, 4067.3085575452246, 4081.163361510883, 4154.231467178552, 4159.396887451333, 4229.788510574459, 60380.0, 61805.0, 62618.68690656518, 63991.0, 64021.359786402005, 9009.0, 9150.313726470542, 9154.221891139328, 9177.908220917705, 9209.677661281823, 16502.75245871313, 16567.50297491077, 16585.336586536643, 16602.50192494227, 16606.0, 6277.52918531112, 6283.465905398092, 6319.905201421982, 6403.551751377358, 6592.17597800275, 3564.144605294711, 3606.6753992140834, 3622.8913132606062, 3677.7609455385314, 3712.3874560697723, 2768.68160161584, 2865.412590419001, 2867.0, 2870.1246119933203, 3254.23525471514, 538.7414041260217, 545.6535100211395, 550.295621604347, 550.3423409026256, 561.719140429785, 446.2814868062417, 509.24631545313144, 560.621580433206, 599.8920194365056, 610.3926593039865, 8568.22885940256, 8652.913470865211, 8723.0, 8907.821843563092, 9010.414323068979, 493.1370102320938, 557.0947210782479, 587.3715124816406, 589.9380565040694, 604.0908432808595, 4033.092554175346, 4034.3545032794614, 4204.684648651363, 4232.724872883253, 4313.611774940207, 76441.61779191068, 99899.0, 102937.48531257296, 109343.45328273308, 110139.0, 49035.754821225666, 72783.0, 75073.62463187649, 75135.0, 75968.62015689997, 71528.2847171532, 72992.63503682447, 74521.62739186377, 74687.62656186793, 75002.0, 8471.745847624581, 8749.956250218962, 9012.63949442015, 9053.094690530894, 9150.908490915006, 44563.554364455944, 45197.77401112973, 45462.31806522971, 45978.54021459743, 46349.304760429295, 58358.7082064604, 59356.40643593509, 63269.68365158145, 63397.36602633914, 63962.68018659877, 10458.895411045793, 10470.685879423629, 10562.894371056498, 10645.520951557353, 11002.834957475805, 12529.561465349034, 12870.67823304425, 13182.80225796633, 13224.272665003517, 13735.725285494435, 11136.276142050741, 11415.315081095161, 11458.427078645867, 11472.139589530652, 11747.29516229029, 12423.751524969632, 12474.75050499003, 12507.187032842836, 12878.162919410208, 12949.158304710541, 12124.636260912186, 12425.316607586308, 12450.0, 13021.739565208833, 13051.543195988475, 9751.317407781387, 10012.148967337858, 10122.696319110439, 10189.082982531607, 10289.74275643085, 9983.201343892326, 10095.444750538498, 10107.0, 10224.488775561342, 10264.794704106025], "value": 6522.664108444323, "unit": "score"}], "name": "dromaeo_css", "value": 6468.307243351861, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 114.0, 112.0, 112.0, 113.0, 112.0, 111.0, 114.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 124.0, 171.0, 121.0, 126.0, 146.0, 127.0, 175.0, 125.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 162.0, 161.0, 163.0, 161.0, 161.0, 159.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 127.0, 76.0, 77.0, 76.0, 75.0, 107.0, 75.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 111.0, 85.0, 87.0, 86.0, 117.0, 85.0, 85.0, 125.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 64.0, 63.0, 65.0, 64.0, 63.0, 64.0, 63.0, 66.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 66.0, 66.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 89.0, 77.0, 83.0, 87.0, 86.0, 85.0, 79.0, 87.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 138.0, 128.0, 146.0, 127.0, 129.0, 129.0, 166.0, 145.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 165.0, 166.0, 165.0, 162.0, 167.0, 161.0, 162.0, 169.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 67.0, 69.0, 67.0, 66.0, 67.0, 66.0, 66.0, 69.0, 64.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1530.7, "alertThreshold": 2.0}]} 21:43:22 INFO - Return code: 0 21:43:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:43:22 INFO - # TBPL SUCCESS # 21:43:22 INFO - Running post-action listener: _resource_record_post_action 21:43:22 INFO - ##### 21:43:22 INFO - ##### Finished run-tests step (success) 21:43:22 INFO - ##### 21:43:22 INFO - Running post-run listener: _resource_record_post_run 21:43:22 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 405504; Write bytes: 300138496; Read time: 156; Write time: 203936 21:43:22 INFO - TinderboxPrint: CPU usage
12.9% 21:43:22 INFO - TinderboxPrint: I/O read bytes / time
405,504 / 156 21:43:22 INFO - TinderboxPrint: I/O write bytes / time
300,138,496 / 203,936 21:43:22 INFO - TinderboxPrint: CPU idle
8,344.7 (87.0%) 21:43:22 INFO - TinderboxPrint: CPU user
1,201.2 (12.5%) 21:43:22 INFO - TinderboxPrint: Swap in / out
0 / 0 21:43:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13336576; Read time: 0; Write time: 5596 21:43:22 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 401408; Write bytes: 286801920; Read time: 144; Write time: 198340 21:43:23 INFO - Running post-run listener: _upload_blobber_files 21:43:23 INFO - Blob upload gear active. 21:43:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:43:23 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 21:43:23 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'] 21:43:23 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 21:43:23 INFO - (blobuploader) - INFO - Open directory for files ... 21:43:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:43:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:43:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:43:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:43:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:43:26 INFO - (blobuploader) - INFO - Done attempting. 21:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:43:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:43:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:43:27 INFO - (blobuploader) - INFO - Done attempting. 21:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:43:28 INFO - (blobuploader) - INFO - Done attempting. 21:43:28 INFO - (blobuploader) - INFO - Iteration through files over. 21:43:28 INFO - Return code: 0 21:43:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:43:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:43:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f2cb082e3f4676ba3b82e7ded978fd5e7e1678c06e72e172560151a3ebadc24e22b9b6680231204467a44f82b5e85ab525ab2da13fc79dc3841f4129ed9c1398", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3e11185207a5ef00b80d8953b9417022a2b7417a2772843e249a043d08dad6bb3e2bf16a516fbe28ea188b54adbde9991c12368b22fbd1dbaf23910ef1bc558", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/667b9b27150c503553977561af65dcdf93bd7047180b37d598b870ec662022ad1f7ee90712deb3005b15749b8d91e2dd764d2f94b2e1b6beb3add2c9524f9046"} 21:43:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:43:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:43:28 INFO - Contents: 21:43:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f2cb082e3f4676ba3b82e7ded978fd5e7e1678c06e72e172560151a3ebadc24e22b9b6680231204467a44f82b5e85ab525ab2da13fc79dc3841f4129ed9c1398", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3e11185207a5ef00b80d8953b9417022a2b7417a2772843e249a043d08dad6bb3e2bf16a516fbe28ea188b54adbde9991c12368b22fbd1dbaf23910ef1bc558", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/667b9b27150c503553977561af65dcdf93bd7047180b37d598b870ec662022ad1f7ee90712deb3005b15749b8d91e2dd764d2f94b2e1b6beb3add2c9524f9046"} 21:43:28 INFO - Running post-run listener: copy_logs_to_upload_dir 21:43:28 INFO - Copying logs to upload dir... 21:43:28 INFO - mkdir: /builds/slave/test/build/upload/logs 21:43:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.880602 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-06 21:43:28.426800) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 21:43:28.429971) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 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/f2cb082e3f4676ba3b82e7ded978fd5e7e1678c06e72e172560151a3ebadc24e22b9b6680231204467a44f82b5e85ab525ab2da13fc79dc3841f4129ed9c1398", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3e11185207a5ef00b80d8953b9417022a2b7417a2772843e249a043d08dad6bb3e2bf16a516fbe28ea188b54adbde9991c12368b22fbd1dbaf23910ef1bc558", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/667b9b27150c503553977561af65dcdf93bd7047180b37d598b870ec662022ad1f7ee90712deb3005b15749b8d91e2dd764d2f94b2e1b6beb3add2c9524f9046"} build_url:https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/d9CCLPMwQz6gyB1C4K4ZQg/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/f2cb082e3f4676ba3b82e7ded978fd5e7e1678c06e72e172560151a3ebadc24e22b9b6680231204467a44f82b5e85ab525ab2da13fc79dc3841f4129ed9c1398", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3e11185207a5ef00b80d8953b9417022a2b7417a2772843e249a043d08dad6bb3e2bf16a516fbe28ea188b54adbde9991c12368b22fbd1dbaf23910ef1bc558", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/667b9b27150c503553977561af65dcdf93bd7047180b37d598b870ec662022ad1f7ee90712deb3005b15749b8d91e2dd764d2f94b2e1b6beb3add2c9524f9046"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 21:43:28.455828) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:43:28.456152) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1467865367.940030-114785405 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:43:28.538590) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 21:43:28.538934) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 21:43:28.539233) ========= ========= Total master_lag: 0.17 =========