builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-003 starttime: 1467761493.7 results: success (0) buildid: 20160705152024 builduid: 1ff2110a49d948e7b026ec253a4e85e6 revision: 9b428173a0889f5a25e7a6e855f2d1726207a723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:33.697842) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:33.698273) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:33.698598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:33.731352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:33.731618) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-05 16:31:34-- 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% 42.8M=0s 2016-07-05 16:31:34 (42.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.368075 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:34.135088) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:34.135365) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016279 ========= master_lag: 0.45 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:34.600508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:34.601014) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9b428173a0889f5a25e7a6e855f2d1726207a723 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9b428173a0889f5a25e7a6e855f2d1726207a723 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-05 16:31:35,112 truncating revision to first 12 chars 2016-07-05 16:31:35,112 Setting DEBUG logging. 2016-07-05 16:31:35,112 attempt 1/10 2016-07-05 16:31:35,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9b428173a088?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 16:31:35,402 unpacking tar archive at: mozilla-central-9b428173a088/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:35.247127) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:35.247423) ========= script_repo_revision: 9b428173a0889f5a25e7a6e855f2d1726207a723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:35.247811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:35.248120) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 16:31:35.263755) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-05 16:31:35.264070) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:31:35 INFO - MultiFileLogger online at 20160705 16:31:35 in /builds/slave/test 16:31:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:31:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:35 INFO - {'append_to_log': False, 16:31:35 INFO - 'base_work_dir': '/builds/slave/test', 16:31:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:31:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:35 INFO - 'branch': 'Firefox-Non-PGO', 16:31:35 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:35 INFO - 'config_files': ('talos/linux_config.py',), 16:31:35 INFO - 'default_actions': ('clobber', 16:31:35 INFO - 'read-buildbot-config', 16:31:35 INFO - 'download-and-extract', 16:31:35 INFO - 'populate-webroot', 16:31:35 INFO - 'create-virtualenv', 16:31:35 INFO - 'install', 16:31:35 INFO - 'run-tests'), 16:31:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:35 INFO - 'download_minidump_stackwalk': True, 16:31:35 INFO - 'download_symbols': 'ondemand', 16:31:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:35 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:35 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:35 INFO - 'installer_path': 'installer.exe', 16:31:35 INFO - 'log_level': 'info', 16:31:35 INFO - 'log_name': 'talos', 16:31:35 INFO - 'log_to_console': True, 16:31:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:31:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:31:35 INFO - 'opt_config_files': (), 16:31:35 INFO - 'pip_index': False, 16:31:35 INFO - 'sps_profile': False, 16:31:35 INFO - 'sps_profile_interval': 0, 16:31:35 INFO - 'suite': 'dromaeojs', 16:31:35 INFO - 'system_bits': '32', 16:31:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:35 INFO - 'title': 'talos-linux64-ix-003', 16:31:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:35 INFO - 'use_talos_json': True, 16:31:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:35 INFO - 'work_dir': 'build'} 16:31:35 INFO - ##### 16:31:35 INFO - ##### Running clobber step. 16:31:35 INFO - ##### 16:31:35 INFO - Running pre-action listener: _resource_record_pre_action 16:31:35 INFO - Running main action method: clobber 16:31:35 INFO - rmtree: /builds/slave/test/build 16:31:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:36 INFO - Running post-action listener: _resource_record_post_action 16:31:36 INFO - ##### 16:31:36 INFO - ##### Finished clobber step (success) 16:31:36 INFO - ##### 16:31:36 INFO - ##### 16:31:36 INFO - ##### Running read-buildbot-config step. 16:31:36 INFO - ##### 16:31:36 INFO - Running pre-action listener: _resource_record_pre_action 16:31:36 INFO - Running main action method: read_buildbot_config 16:31:36 INFO - Using buildbot properties: 16:31:36 INFO - { 16:31:36 INFO - "project": "", 16:31:36 INFO - "product": "firefox", 16:31:36 INFO - "script_repo_revision": "production", 16:31:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:31:36 INFO - "repository": "", 16:31:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:31:36 INFO - "buildid": "20160705152024", 16:31:36 INFO - "pgo_build": "False", 16:31:36 INFO - "basedir": "/builds/slave/test", 16:31:36 INFO - "buildnumber": 634, 16:31:36 INFO - "slavename": "talos-linux64-ix-003", 16:31:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:31:36 INFO - "platform": "ubuntu64_hw", 16:31:36 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:31:36 INFO - "branch": "mozilla-central", 16:31:36 INFO - "repo_path": "mozilla-central", 16:31:36 INFO - "revision": "9b428173a0889f5a25e7a6e855f2d1726207a723", 16:31:36 INFO - "stage_platform": "linux64", 16:31:36 INFO - "builduid": "1ff2110a49d948e7b026ec253a4e85e6", 16:31:36 INFO - "slavebuilddir": "test" 16:31:36 INFO - } 16:31:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:31:36 INFO - Running post-action listener: _resource_record_post_action 16:31:36 INFO - ##### 16:31:36 INFO - ##### Finished read-buildbot-config step (success) 16:31:36 INFO - ##### 16:31:36 INFO - ##### 16:31:36 INFO - ##### Running download-and-extract step. 16:31:36 INFO - ##### 16:31:36 INFO - Running pre-action listener: _resource_record_pre_action 16:31:36 INFO - Running main action method: download_and_extract 16:31:36 INFO - mkdir: /builds/slave/test/build/tests 16:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:36 INFO - https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:31:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:31:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:31:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:42 INFO - Downloaded 1361 bytes. 16:31:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:31:42 INFO - Using the following test package requirements: 16:31:42 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:31:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:31:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:31:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'jsshell-linux-x86_64.zip'], 16:31:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:31:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:31:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:31:42 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:31:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:31:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:31:42 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:31:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:31:42 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')]} 16:31:42 INFO - https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:31:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:31:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:31:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:44 INFO - Downloaded 22730630 bytes. 16:31:44 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'] 16:31:44 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 16:31:45 INFO - Return code: 0 16:31:45 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 16:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:45 INFO - https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:31:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:31:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:31:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:54 INFO - Downloaded 22730630 bytes. 16:31: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'] 16:31: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 16:31:55 INFO - Return code: 0 16:31: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')]} 16:31:55 INFO - https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:31:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:31:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:31:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:59 INFO - Downloaded 14403333 bytes. 16:31:59 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'] 16:31:59 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 16:31:59 INFO - Return code: 0 16:31:59 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')]} 16:31:59 INFO - https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYGhAeQDRKWgxPxErh4PoA/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 16:32:00 INFO - Downloaded 56497262 bytes. 16:32:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:32:00 INFO - mkdir: /builds/slave/test/properties 16:32:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:32:00 INFO - Writing to file /builds/slave/test/properties/build_url 16:32:00 INFO - Contents: 16:32:00 INFO - build_url:https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:32:01 INFO - Running post-action listener: _resource_record_post_action 16:32:01 INFO - Running post-action listener: set_extra_try_arguments 16:32:01 INFO - ##### 16:32:01 INFO - ##### Finished download-and-extract step (success) 16:32:01 INFO - ##### 16:32:01 INFO - ##### 16:32:01 INFO - ##### Running populate-webroot step. 16:32:01 INFO - ##### 16:32:01 INFO - Running pre-action listener: _resource_record_pre_action 16:32:01 INFO - Running main action method: populate_webroot 16:32:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:32:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:32:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:32:01 INFO - '--tppagecycles', 16:32:01 INFO - '7'], 16:32:01 INFO - 'tests': ['tsvgm']}}, 16:32:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:32:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:32:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:32:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:32:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['damp', 'tps']}, 16:32:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'tests': ['damp', 'tps']}, 16:32:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['dromaeo_dom']}, 16:32:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:32:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['basic_compositor_video']}, 16:32:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:32:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'other-e10s': {'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['a11yr', 16:32:01 INFO - 'ts_paint', 16:32:01 INFO - 'tpaint', 16:32:01 INFO - 'sessionrestore', 16:32:01 INFO - 'sessionrestore_no_auto_restore', 16:32:01 INFO - 'tabpaint']}, 16:32:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['tsvgx', 16:32:01 INFO - 'tsvgr_opacity', 16:32:01 INFO - 'tart', 16:32:01 INFO - 'tscrollx', 16:32:01 INFO - 'cart']}, 16:32:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:32:01 INFO - 'tsvgr_opacity', 16:32:01 INFO - 'tart', 16:32:01 INFO - 'tscrollx', 16:32:01 INFO - 'cart']}, 16:32:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'talos_options': ['--disable-e10s'], 16:32:01 INFO - 'tests': ['tp5o']}, 16:32:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'tests': ['tp5o']}, 16:32:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'talos_options': ['--disable-e10s', 16:32:01 INFO - '--xperf_path', 16:32:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:01 INFO - 'tests': ['tp5n']}, 16:32:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:01 INFO - 'talos_options': ['--xperf_path', 16:32:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:01 INFO - 'tests': ['tp5n']}}, 16:32:01 INFO - 'talos.zip': {'path': '', 16:32:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:32:01 INFO - Running post-action listener: _resource_record_post_action 16:32:01 INFO - ##### 16:32:01 INFO - ##### Finished populate-webroot step (success) 16:32:01 INFO - ##### 16:32:01 INFO - ##### 16:32:01 INFO - ##### Running create-virtualenv step. 16:32:01 INFO - ##### 16:32:01 INFO - Running pre-action listener: _resource_record_pre_action 16:32:01 INFO - Running main action method: create_virtualenv 16:32:01 INFO - Creating virtualenv /builds/slave/test/build/venv 16:32:01 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 16:32:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:32:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:32:01 INFO - Using real prefix '/usr' 16:32:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:32:02 INFO - Installing distribute.............................................................................................................................................................................................done. 16:32:04 INFO - Installing pip.................done. 16:32:04 INFO - Return code: 0 16:32:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:32:04 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')]} 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 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')]} 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:04 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 16:32:04 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 16:32:04 INFO - Using env: {'DISPLAY': ':0', 16:32:04 INFO - 'HOME': '/home/cltbld', 16:32:04 INFO - 'LANG': 'en_US.UTF-8', 16:32:04 INFO - 'LANGUAGE': 'en_US:en', 16:32:04 INFO - 'LOGNAME': 'cltbld', 16:32:04 INFO - 'MAIL': '/var/mail/cltbld', 16:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:04 INFO - 'MOZ_NO_REMOTE': '1', 16:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:04 INFO - 'NO_EM_RESTART': '1', 16:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:04 INFO - 'PWD': '/builds/slave/test', 16:32:04 INFO - 'SHELL': '/bin/bash', 16:32:04 INFO - 'SHLVL': '1', 16:32:04 INFO - 'TERM': 'linux', 16:32:04 INFO - 'TMOUT': '86400', 16:32:04 INFO - 'USER': 'cltbld', 16:32:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:04 INFO - '_': '/tools/buildbot/bin/python'} 16:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:04 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 16:32:04 INFO - Cleaning up... 16:32:04 INFO - Return code: 0 16:32:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:32:04 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')]} 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 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')]} 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:04 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 16:32:04 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 16:32:04 INFO - Using env: {'DISPLAY': ':0', 16:32:04 INFO - 'HOME': '/home/cltbld', 16:32:04 INFO - 'LANG': 'en_US.UTF-8', 16:32:04 INFO - 'LANGUAGE': 'en_US:en', 16:32:04 INFO - 'LOGNAME': 'cltbld', 16:32:04 INFO - 'MAIL': '/var/mail/cltbld', 16:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:04 INFO - 'MOZ_NO_REMOTE': '1', 16:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:04 INFO - 'NO_EM_RESTART': '1', 16:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:04 INFO - 'PWD': '/builds/slave/test', 16:32:04 INFO - 'SHELL': '/bin/bash', 16:32:04 INFO - 'SHLVL': '1', 16:32:04 INFO - 'TERM': 'linux', 16:32:04 INFO - 'TMOUT': '86400', 16:32:04 INFO - 'USER': 'cltbld', 16:32:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:04 INFO - '_': '/tools/buildbot/bin/python'} 16:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:04 INFO - Downloading/unpacking psutil>=3.1.1 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:32:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:06 INFO - Installing collected packages: psutil 16:32:06 INFO - Running setup.py install for psutil 16:32:06 INFO - building 'psutil._psutil_linux' extension 16:32:06 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 16:32:06 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 16:32:06 INFO - building 'psutil._psutil_posix' extension 16:32:06 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 16:32:06 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 16:32:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:06 INFO - Successfully installed psutil 16:32:06 INFO - Cleaning up... 16:32:07 INFO - Return code: 0 16:32:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:32: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 mozsystemmonitor==0.3 16:32:07 INFO - Using env: {'DISPLAY': ':0', 16:32:07 INFO - 'HOME': '/home/cltbld', 16:32:07 INFO - 'LANG': 'en_US.UTF-8', 16:32:07 INFO - 'LANGUAGE': 'en_US:en', 16:32:07 INFO - 'LOGNAME': 'cltbld', 16:32:07 INFO - 'MAIL': '/var/mail/cltbld', 16:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:07 INFO - 'MOZ_NO_REMOTE': '1', 16:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:07 INFO - 'NO_EM_RESTART': '1', 16:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:07 INFO - 'PWD': '/builds/slave/test', 16:32:07 INFO - 'SHELL': '/bin/bash', 16:32:07 INFO - 'SHLVL': '1', 16:32:07 INFO - 'TERM': 'linux', 16:32:07 INFO - 'TMOUT': '86400', 16:32:07 INFO - 'USER': 'cltbld', 16:32:07 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:07 INFO - '_': '/tools/buildbot/bin/python'} 16:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:07 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 16:32:07 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 16:32:07 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 16:32:07 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 16:32:07 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 16:32:07 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 16:32:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:09 INFO - Installing collected packages: mozsystemmonitor 16:32:09 INFO - Running setup.py install for mozsystemmonitor 16:32:09 INFO - Successfully installed mozsystemmonitor 16:32:09 INFO - Cleaning up... 16:32:09 INFO - Return code: 0 16:32:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:09 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 16:32:09 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 16:32:09 INFO - Using env: {'DISPLAY': ':0', 16:32:09 INFO - 'HOME': '/home/cltbld', 16:32:09 INFO - 'LANG': 'en_US.UTF-8', 16:32:09 INFO - 'LANGUAGE': 'en_US:en', 16:32:09 INFO - 'LOGNAME': 'cltbld', 16:32:09 INFO - 'MAIL': '/var/mail/cltbld', 16:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:09 INFO - 'MOZ_NO_REMOTE': '1', 16:32:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:09 INFO - 'NO_EM_RESTART': '1', 16:32:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:09 INFO - 'PWD': '/builds/slave/test', 16:32:09 INFO - 'SHELL': '/bin/bash', 16:32:09 INFO - 'SHLVL': '1', 16:32:09 INFO - 'TERM': 'linux', 16:32:09 INFO - 'TMOUT': '86400', 16:32:09 INFO - 'USER': 'cltbld', 16:32:09 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:09 INFO - '_': '/tools/buildbot/bin/python'} 16:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:09 INFO - Downloading/unpacking jsonschema==2.5.1 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32:11 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 16:32:11 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 16:32:11 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 16:32:11 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 16:32:11 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 16:32:11 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 16:32:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:11 INFO - warning: no files found matching '*.txt' 16:32:11 INFO - no previously-included directories found matching 'build' 16:32:11 INFO - no previously-included directories found matching 'dist' 16:32:11 INFO - no previously-included directories found matching '.git*' 16:32:11 INFO - Installing collected packages: jsonschema, functools32 16:32:11 INFO - Running setup.py install for functools32 16:32:11 INFO - warning: no files found matching '*.txt' 16:32:11 INFO - no previously-included directories found matching 'build' 16:32:11 INFO - no previously-included directories found matching 'dist' 16:32:11 INFO - no previously-included directories found matching '.git*' 16:32:11 INFO - Successfully installed jsonschema functools32 16:32:11 INFO - Cleaning up... 16:32:11 INFO - Return code: 0 16:32:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:11 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')]} 16:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:11 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')]} 16:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:11 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:11 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 16:32:11 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 16:32:11 INFO - Using env: {'DISPLAY': ':0', 16:32:11 INFO - 'HOME': '/home/cltbld', 16:32:11 INFO - 'LANG': 'en_US.UTF-8', 16:32:11 INFO - 'LANGUAGE': 'en_US:en', 16:32:11 INFO - 'LOGNAME': 'cltbld', 16:32:11 INFO - 'MAIL': '/var/mail/cltbld', 16:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:11 INFO - 'MOZ_NO_REMOTE': '1', 16:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:11 INFO - 'NO_EM_RESTART': '1', 16:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:11 INFO - 'PWD': '/builds/slave/test', 16:32:11 INFO - 'SHELL': '/bin/bash', 16:32:11 INFO - 'SHLVL': '1', 16:32:11 INFO - 'TERM': 'linux', 16:32:11 INFO - 'TMOUT': '86400', 16:32:11 INFO - 'USER': 'cltbld', 16:32:11 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:11 INFO - '_': '/tools/buildbot/bin/python'} 16:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:12 INFO - Downloading/unpacking blobuploader==1.2.4 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 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 16:32:14 INFO - Downloading docopt-0.6.1.tar.gz 16:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:14 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:14 INFO - Running setup.py install for blobuploader 16:32:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:14 INFO - Running setup.py install for requests 16:32:15 INFO - Running setup.py install for docopt 16:32:15 INFO - Successfully installed blobuploader requests docopt 16:32:15 INFO - Cleaning up... 16:32:15 INFO - Return code: 0 16:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:15 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 16:32:15 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 16:32:15 INFO - Using env: {'DISPLAY': ':0', 16:32:15 INFO - 'HOME': '/home/cltbld', 16:32:15 INFO - 'LANG': 'en_US.UTF-8', 16:32:15 INFO - 'LANGUAGE': 'en_US:en', 16:32:15 INFO - 'LOGNAME': 'cltbld', 16:32:15 INFO - 'MAIL': '/var/mail/cltbld', 16:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:15 INFO - 'MOZ_NO_REMOTE': '1', 16:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:15 INFO - 'NO_EM_RESTART': '1', 16:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:15 INFO - 'PWD': '/builds/slave/test', 16:32:15 INFO - 'SHELL': '/bin/bash', 16:32:15 INFO - 'SHLVL': '1', 16:32:15 INFO - 'TERM': 'linux', 16:32:15 INFO - 'TMOUT': '86400', 16:32:15 INFO - 'USER': 'cltbld', 16:32:15 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:15 INFO - '_': '/tools/buildbot/bin/python'} 16:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:15 INFO - Running setup.py (path:/tmp/pip-e4bv7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:15 INFO - Running setup.py (path:/tmp/pip-EHgl6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:15 INFO - Running setup.py (path:/tmp/pip-NdW7YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:15 INFO - Running setup.py (path:/tmp/pip-VT7GWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:15 INFO - Running setup.py (path:/tmp/pip-V8KRBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:15 INFO - Running setup.py (path:/tmp/pip-6PVM3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:16 INFO - Running setup.py (path:/tmp/pip-sOsNRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:16 INFO - Running setup.py (path:/tmp/pip-MyPs0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:16 INFO - Running setup.py (path:/tmp/pip-uxOaMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:16 INFO - Running setup.py (path:/tmp/pip-po9HoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:16 INFO - Running setup.py (path:/tmp/pip-QVjKHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:16 INFO - Running setup.py (path:/tmp/pip-lM1Zq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:16 INFO - Running setup.py (path:/tmp/pip-KygfLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:16 INFO - Running setup.py (path:/tmp/pip-aEODBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:16 INFO - Running setup.py (path:/tmp/pip-5piJYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:16 INFO - Running setup.py (path:/tmp/pip-1bHnTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:16 INFO - Running setup.py (path:/tmp/pip-Np8Bw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:16 INFO - Running setup.py install for manifestparser 16:32:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:16 INFO - Running setup.py install for mozcrash 16:32:17 INFO - Running setup.py install for mozdebug 16:32:17 INFO - Running setup.py install for mozdevice 16:32:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozfile 16:32:17 INFO - Running setup.py install for mozhttpd 16:32:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozinfo 16:32:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozInstall 16:32:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozleak 16:32:17 INFO - Running setup.py install for mozlog 16:32:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for moznetwork 16:32:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozprocess 16:32:18 INFO - Running setup.py install for mozprofile 16:32:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Running setup.py install for mozrunner 16:32:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Running setup.py install for mozscreenshot 16:32:18 INFO - Running setup.py install for moztest 16:32:18 INFO - Running setup.py install for mozversion 16:32:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:18 INFO - Cleaning up... 16:32:18 INFO - Return code: 0 16:32:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:18 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')]} 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:18 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')]} 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:18 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:18 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 16:32:18 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 16:32:18 INFO - Using env: {'DISPLAY': ':0', 16:32:18 INFO - 'HOME': '/home/cltbld', 16:32:18 INFO - 'LANG': 'en_US.UTF-8', 16:32:18 INFO - 'LANGUAGE': 'en_US:en', 16:32:18 INFO - 'LOGNAME': 'cltbld', 16:32:18 INFO - 'MAIL': '/var/mail/cltbld', 16:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:18 INFO - 'MOZ_NO_REMOTE': '1', 16:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:18 INFO - 'NO_EM_RESTART': '1', 16:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:18 INFO - 'PWD': '/builds/slave/test', 16:32:18 INFO - 'SHELL': '/bin/bash', 16:32:18 INFO - 'SHLVL': '1', 16:32:18 INFO - 'TERM': 'linux', 16:32:18 INFO - 'TMOUT': '86400', 16:32:18 INFO - 'USER': 'cltbld', 16:32:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:18 INFO - '_': '/tools/buildbot/bin/python'} 16:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:18 INFO - Running setup.py (path:/tmp/pip-pXIwTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:18 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)) 16:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:18 INFO - Running setup.py (path:/tmp/pip-XBtgQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:19 INFO - Running setup.py (path:/tmp/pip-SIjV6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:19 INFO - Running setup.py (path:/tmp/pip-ziABFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:19 INFO - Running setup.py (path:/tmp/pip-VlHLJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:19 INFO - Running setup.py (path:/tmp/pip-pvqKe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:19 INFO - Running setup.py (path:/tmp/pip-JJNsD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:19 INFO - Running setup.py (path:/tmp/pip-BGhFLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:19 INFO - Running setup.py (path:/tmp/pip-JwJuMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:19 INFO - Running setup.py (path:/tmp/pip-gULkSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:19 INFO - Running setup.py (path:/tmp/pip-w9Lm_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:19 INFO - Running setup.py (path:/tmp/pip-zzt7yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:19 INFO - Running setup.py (path:/tmp/pip-JNUiyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:19 INFO - Running setup.py (path:/tmp/pip-gZ7I6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:19 INFO - Running setup.py (path:/tmp/pip-n527yS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:19 INFO - Running setup.py (path:/tmp/pip-LvDad2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:20 INFO - Running setup.py (path:/tmp/pip-G56SFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:20 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)) 16:32:20 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)) 16:32:20 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)) 16:32:20 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)) 16:32:20 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)) 16:32:20 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)) 16:32:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32:20 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 16:32:20 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 16:32:20 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 16:32:20 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 16:32:20 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 16:32:20 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 16:32:21 INFO - Downloading blessings-1.6.tar.gz 16:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:22 INFO - Installing collected packages: blessings 16:32:22 INFO - Running setup.py install for blessings 16:32:22 INFO - Successfully installed blessings 16:32:22 INFO - Cleaning up... 16:32:22 INFO - Return code: 0 16:32:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:22 INFO - Reading from file tmpfile_stdout 16:32:22 INFO - Current package versions: 16:32:22 INFO - argparse == 1.2.1 16:32:22 INFO - blessings == 1.6 16:32:22 INFO - blobuploader == 1.2.4 16:32:22 INFO - docopt == 0.6.1 16:32:22 INFO - functools32 == 3.2.3-2 16:32:22 INFO - jsonschema == 2.5.1 16:32:22 INFO - manifestparser == 1.1 16:32:22 INFO - mozInstall == 1.12 16:32:22 INFO - mozcrash == 0.17 16:32:22 INFO - mozdebug == 0.1 16:32:22 INFO - mozdevice == 0.48 16:32:22 INFO - mozfile == 1.2 16:32:22 INFO - mozhttpd == 0.7 16:32:22 INFO - mozinfo == 0.9 16:32:22 INFO - mozleak == 0.1 16:32:22 INFO - mozlog == 3.2 16:32:22 INFO - moznetwork == 0.27 16:32:22 INFO - mozprocess == 0.22 16:32:22 INFO - mozprofile == 0.28 16:32:22 INFO - mozrunner == 6.11 16:32:22 INFO - mozscreenshot == 0.1 16:32:22 INFO - mozsystemmonitor == 0.3 16:32:22 INFO - moztest == 0.7 16:32:22 INFO - mozversion == 1.4 16:32:22 INFO - psutil == 3.1.1 16:32:22 INFO - requests == 1.2.3 16:32:22 INFO - wsgiref == 0.1.2 16:32:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:22 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:22 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 16:32:22 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 16:32:22 INFO - Using env: {'DISPLAY': ':0', 16:32:22 INFO - 'HOME': '/home/cltbld', 16:32:22 INFO - 'LANG': 'en_US.UTF-8', 16:32:22 INFO - 'LANGUAGE': 'en_US:en', 16:32:22 INFO - 'LOGNAME': 'cltbld', 16:32:22 INFO - 'MAIL': '/var/mail/cltbld', 16:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:22 INFO - 'MOZ_NO_REMOTE': '1', 16:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:22 INFO - 'NO_EM_RESTART': '1', 16:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:22 INFO - 'PWD': '/builds/slave/test', 16:32:22 INFO - 'SHELL': '/bin/bash', 16:32:22 INFO - 'SHLVL': '1', 16:32:22 INFO - 'TERM': 'linux', 16:32:22 INFO - 'TMOUT': '86400', 16:32:22 INFO - 'USER': 'cltbld', 16:32:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:22 INFO - '_': '/tools/buildbot/bin/python'} 16:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 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)) 16:32:22 INFO - Cleaning up... 16:32:22 INFO - Return code: 0 16:32:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', '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 16:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:32:22 INFO - Using env: {'DISPLAY': ':0', 16:32:22 INFO - 'HOME': '/home/cltbld', 16:32:22 INFO - 'LANG': 'en_US.UTF-8', 16:32:22 INFO - 'LANGUAGE': 'en_US:en', 16:32:22 INFO - 'LOGNAME': 'cltbld', 16:32:22 INFO - 'MAIL': '/var/mail/cltbld', 16:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:22 INFO - 'MOZ_NO_REMOTE': '1', 16:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:22 INFO - 'NO_EM_RESTART': '1', 16:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:22 INFO - 'PWD': '/builds/slave/test', 16:32:22 INFO - 'SHELL': '/bin/bash', 16:32:22 INFO - 'SHLVL': '1', 16:32:22 INFO - 'TERM': 'linux', 16:32:22 INFO - 'TMOUT': '86400', 16:32:22 INFO - 'USER': 'cltbld', 16:32:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:22 INFO - '_': '/tools/buildbot/bin/python'} 16:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:22 INFO - Cleaning up... 16:32:22 INFO - Return code: 0 16:32:22 INFO - Running post-action listener: _resource_record_post_action 16:32:22 INFO - Running post-action listener: _start_resource_monitoring 16:32:22 INFO - Starting resource monitoring. 16:32:22 INFO - ##### 16:32:22 INFO - ##### Finished create-virtualenv step (success) 16:32:22 INFO - ##### 16:32:22 INFO - ##### 16:32:22 INFO - ##### Running install step. 16:32:22 INFO - ##### 16:32:22 INFO - Running pre-action listener: _resource_record_pre_action 16:32:22 INFO - Running main action method: install 16:32:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:23 INFO - Reading from file tmpfile_stdout 16:32:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:23 INFO - Reading from file tmpfile_stdout 16:32:23 INFO - Output received: 16:32:23 INFO - Usage: mozinstall [options] installer 16:32:23 INFO - Options: 16:32:23 INFO - -h, --help show this help message and exit 16:32:23 INFO - -d DEST, --destination=DEST 16:32:23 INFO - Directory to install application into. [default: 16:32:23 INFO - "/builds/slave/test"] 16:32:23 INFO - --app=APP Application being installed. [default: firefox] 16:32:23 INFO - mkdir: /builds/slave/test/build/application 16:32:23 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'] 16:32:23 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 16:32:39 INFO - Reading from file tmpfile_stdout 16:32:39 INFO - Output received: 16:32:39 INFO - /builds/slave/test/build/application/firefox/firefox 16:32:39 INFO - Running post-action listener: _resource_record_post_action 16:32:39 INFO - ##### 16:32:39 INFO - ##### Finished install step (success) 16:32:39 INFO - ##### 16:32:39 INFO - ##### 16:32:39 INFO - ##### Running run-tests step. 16:32:39 INFO - ##### 16:32:39 INFO - Running pre-action listener: _resource_record_pre_action 16:32:39 INFO - Running main action method: run_tests 16:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:39 INFO - Python 2.7.3 16:32:39 INFO - Return code: 0 16:32:39 INFO - grabbing minidump binary from tooltool 16:32:39 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')]} 16:32:39 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 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:39 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 16:32:39 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 16:32:39 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 16:32:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:39 INFO - Return code: 0 16:32:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:32:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:32:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:32:39 INFO - Using env: {'DISPLAY': ':0', 16:32:39 INFO - 'HOME': '/home/cltbld', 16:32:39 INFO - 'LANG': 'en_US.UTF-8', 16:32:39 INFO - 'LANGUAGE': 'en_US:en', 16:32:39 INFO - 'LOGNAME': 'cltbld', 16:32:39 INFO - 'MAIL': '/var/mail/cltbld', 16:32:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:39 INFO - 'MOZ_NO_REMOTE': '1', 16:32:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:39 INFO - 'NO_EM_RESTART': '1', 16:32:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:39 INFO - 'PWD': '/builds/slave/test', 16:32:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:39 INFO - 'SHELL': '/bin/bash', 16:32:39 INFO - 'SHLVL': '1', 16:32:39 INFO - 'TERM': 'linux', 16:32:39 INFO - 'TMOUT': '86400', 16:32:39 INFO - 'USER': 'cltbld', 16:32:39 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1467758384.5110-570285398', 16:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:39 INFO - '_': '/tools/buildbot/bin/python'} 16:32:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:32:39 INFO - mozversion application_buildid: 20160705152024 16:32:39 INFO - mozversion application_changeset: 9b428173a0889f5a25e7a6e855f2d1726207a723 16:32:39 INFO - mozversion application_display_name: Nightly 16:32:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:39 INFO - mozversion application_name: Firefox 16:32:39 INFO - mozversion application_remotingname: firefox 16:32:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:32:39 INFO - mozversion application_vendor: Mozilla 16:32:39 INFO - mozversion application_version: 50.0a1 16:32:39 INFO - mozversion platform_buildid: 20160705152024 16:32:39 INFO - mozversion platform_changeset: 9b428173a0889f5a25e7a6e855f2d1726207a723 16:32:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:32:39 INFO - mozversion platform_version: 50.0a1 16:32:39 INFO - using testdate: 1467761559 16:32:39 INFO - actual date: 1467761559 16:32:39 INFO - starting webserver on 'localhost:39569' 16:32:39 INFO - SUITE-START | Running 2 tests 16:32:39 INFO - TEST-START | dromaeo_css 16:32:39 INFO - operating with platform_type : linux_ 16:32:39 INFO - Initialising browser for dromaeo_css test... 16:32:39 INFO - TEST-INFO | started process 16375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuxcivT/profile http://localhost:39569/getInfo.html) 16:32:44 INFO - PROCESS | 16375 | __metrics Screen width/height:1600/1200 16:32:44 INFO - PROCESS | 16375 | colorDepth:24 16:32:44 INFO - PROCESS | 16375 | Browser inner width/height: 1024/697 16:32:44 INFO - PROCESS | 16375 | __metrics 16:32:45 INFO - PROCESS | 16375 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:46 INFO - TEST-INFO | 16375: exit 0 16:32:46 INFO - Browser initialized. 16:32:46 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:46 INFO - TEST-INFO | started process 16457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuxcivT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:58 INFO - PROCESS | 16457 | RSS: Main: 159031296 16:32:58 INFO - PROCESS | 16457 | 16:33:49 INFO - PROCESS | 16457 | 1467761629439 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] 16:36:02 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39569/tests/dromaeo/cssquery-ext.html) 16:36:03 INFO - PROCESS | 16457 | RSS: Main: 235466752 16:36:03 INFO - PROCESS | 16457 | 16:39:06 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-ext.html (next: http://localhost:39569/tests/dromaeo/cssquery-jquery.html) 16:39:06 INFO - PROCESS | 16457 | RSS: Main: 235216896 16:39:06 INFO - PROCESS | 16457 | 16:42:09 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39569/tests/dromaeo/cssquery-mootools.html) 16:42:09 INFO - PROCESS | 16457 | RSS: Main: 241258496 16:42:09 INFO - PROCESS | 16457 | 16:45:11 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39569/tests/dromaeo/cssquery-prototype.html) 16:45:12 INFO - PROCESS | 16457 | RSS: Main: 244862976 16:45:12 INFO - PROCESS | 16457 | 16:48:15 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39569/tests/dromaeo/cssquery-yui.html) 16:48:16 INFO - PROCESS | 16457 | RSS: Main: 219762688 16:48:16 INFO - PROCESS | 16457 | 16:51:18 INFO - PROCESS | 16457 | Cycle 1(1): loaded http://localhost:39569/tests/dromaeo/cssquery-yui.html (next: http://localhost:39569/tests/dromaeo/cssquery-dojo.html) 16:51:19 INFO - PROCESS | 16457 | RSS: Main: 289288192 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | __start_tp_report 16:51:19 INFO - PROCESS | 16457 | _x_x_mozilla_page_load 16:51:19 INFO - PROCESS | 16457 | _x_x_mozilla_page_load_details 16:51:19 INFO - PROCESS | 16457 | |i|pagename|runs| 16:51:19 INFO - PROCESS | 16457 | |0;dojo.html;2570.280321509978;2587.909423170189;2603.7786788123008;2619.4499155177414;2629.9737002629968;4564.612007979325;4571.51999040101;4578.450585929689;4605.907881842361;4640.280756482742;4582.633389328854;4588.403507544023;4596.632269418446;4597.655175861815;4620.537946205378;4080.7347522411096;4217.21981433435;4286.7856607169515;4314.331278651814;4441.133978874121;4323.978380108111;4397.934030989538;4399.2521271383785;4409.118176364724;4442.62237709795;4230.302000169968;4242.215190189794;4267.167902259061;4280.079782846703;4331.891702707426;4238.300680387732;4251.06476575153;4289.742615443052;4303.225419424502;4307.224699554079;4293.205756934977;4296.978515107405;4297.828086876521;4320.308750599889;4337.132573485315;4570.611498022672;4643.674942754009;4696.671233013691;4726.858194254178;4743.241081426955;4996.200607902754;5021.799128034874;5053.8231161909525;5055.620828437882;5069.391672999227;4890.73100979446;4921.483244259372;5036.244563315496;5081.110805609041;5096.388433388017;4615.792289346988;4700.083483720641;4706.647001474903;4706.835260765925;4708.505606911293;5105.693658380509;5145.948540514547;5147.356580427447;5149.201873709581;5152.639315247897;4786.8085276589245;4936.827211047596;4938.407391113089;4940.061388336205;4940.555350018516;4948.282499037619;5002.799888004512;5012.172991456442;5080.5681517070725;5085.262636917626;4968.4286307075145;4969.378827646544;4973.825916092992;4975.203967365276;5026.170681837529;4825.831095911696;4908.656394052382;4955.529224723646;4966.900661986812;4968.056069346812;4193.391958166048;4196.685248606367;4198.958010419857;4221.68337374692;4242.193983143193;3680.2087551176623;3694.556653201633;3696.722745794076;3726.403775395978;3743.9064023399633;4267.679924005712;4288.09949910516;4318.632916202095;4368.344748287782;4374.19077470669;4977.576905962961;5007.4491805901325;5039.571636410946;5040.243963405518;5087.669301495391;4342.478902531717;4349.804258808361;4353.064091220403;4355.477342718894;4393.428854248928;4417.359482874966;4422.911541769211;4434.046679963823;4444.444444444444;4482.551744825557;4724.669273150984;4730;4753.548412900769;4780.4741478437345;4802.663813533159;5030.798768049237;5049.7727602257255;5050.090983622983;5054.519820617035;5072.391313042459;4580.862574122782;4619.815207391666;4629.583337499641;4756.500567440369;4799.3041009052795;4857.222844344888;4880.31675565414;4883.853484395474;4894.559489645949;4918.631102667243;4967.577755890789;4982.800687972441;4985.102681517362;4986.576141027908;5004.87487812793;4741.051789642016;4876.195427754523;4920.6555541111775;4921.212605983025;4922;4866.829660961849;4893.486183950777;4906.484819094087;4906.926396104132;4956.9752151240455;4842.685225460334;4853.975730121327;4860.708357498562;4901.90196196067;4908.828191013297;4719.4100737407825;4841.8547443576745;4865.7323847188745;4892.217245240744;4898.24077268024;4780.378550788375;4788.664793464424;4788.784504697227;4789.8323558676675;4847.854564363075 16:51:19 INFO - PROCESS | 16457 | |1;ext.html;553.8055547812189;577.7891069759538;579.3858509979419;588.861617519883;595.9970200148998;27510.174694759164;30231.697683023136;30254.09237722869;40036.59963400365;40849.18301633966;42305.365419518654;42736.572634273645;42773.57226427735;42784;42946.06742696578;7508.962455187744;7947.880781788293;8096.230858068474;8103.189681031909;8105.75682729519;7514.962425187894;7768.262015108556;7776.222377762235;7777.5333479991095;10464.110550603169;10149.797004059914;10164.949175254113;10169.847452288222;10174.287799854013;10216.540255688511;10330.58677652893;10443.530041148166;10451.425171615558;10470.581176752921;10502.632407865687;7573.469857110004;10048.69853904384;10356.275060745751;10372.844407333896;10382.688519344432;39323.41014884779;39576;39712.404313935316;39775;39814;3052.6031615890015;3262.6900444457738;3263.216827961296;3519.560054993126;3665.596784353719;30029.099127026224;30147.397052058837;30227.395452090834;30849.845750771103;30939.226519337197;3503.8248087595775;3510.7191424685966;3531.5408996830506;3532.0993146747674;3581.5164952731307;246278;246684;246796;246833;246913.9999999982;53182.73408632932;53694.46305536895;53762.46237537575;53791.731041344545;53877;56448;56682.716586416806;56838.43161568414;56953;57047;14211.715765684628;14620.342084606218;14670;14680.339384727713;14718.852811471963;11619.941900290445;11662.125340599488;11694.824577631342;11695.824562631567;11713.824292635618;14626.341814618369;16199;16865.91567042157;16937.153142342835;17367.56581085483;5885.146653735184;5887.61730487517;5963.701814909324;6010.909836352458;6023.27720673522;794.2891112454321;799.4883274704194;813.0812182234038;922.5387306346827;1021.1568119544294;27823.30441738932;28108.297292567848;28259;28617.14148575546;28674;962.5331714118647;965.8551217317458;970.4080510888352;996.1134590214715;1003.7490627343165;13837.238951857544;13856.653583660489;13997.930010349883;14190.716185676229;14240.857591424161;358657;361423;361724;361829;362121;49655.503444965085;49692.50307496951;49701.751491242314;49811.50188498069;49821.75089124531;1079.7516571188671;1119.798436281461;1168.9941550292194;1221.969450763738;1235.8764123587569;28708.712912870604;29687;29774;30507.694923050487;30640.233994150323;42749.14501709886;47007.29489057735;48477;48486.5151348482;53138.734306328224;263847;264217;264245;264726;265320;22148.2248121315;22747.88626056859;22788.43028924224;25122.62316065297;25505.744942550336;34341.656583434844;35644.28711425809;43002.35496467493;44899;48646.5135348642;36046;37812.243755125295;38209.733487833626;38462.230755384175;38755.61244387633;36760.448593271656;37045;37420;37476.812615936746;37666.623333766314;35956.640433596374;36445;36613.26773464569;36684;36767.44848827216;32596.67403326031;33422.49866252057;34279;34322.65677343195;34412.31175376428;25295.36761580975;25335.74664253334;25644.743552564236;25724.228273151835;26286.73713262843 16:51:19 INFO - PROCESS | 16457 | |2;jquery.html;352.17590837440383;363.8490026638944;370.5941993517098;385.91702783901457;398.57949862894634;13033.934830325847;16362.918185409071;17044;18181.909090454563;18432.631347373044;17023.9785612863;17101.572460688458;17231.396901108463;17352.739708904344;17416.216270267836;5012.997400519902;5204.271402003704;5289.233061206123;5295.28513650658;5300.178472336794;3773.358529050054;5154.716490593016;5200.245964335169;5822.330432000311;6133.447989680928;6157.415045570664;6178.474829639464;6180.536459765513;6186.536009799261;6193.535484838632;5928.081147422156;6100.23747031621;6194.752209911598;6197.845053873644;6206.224221972254;3442.208292092808;3619.94570081449;3622.040159357772;5430.619856610039;5434.809781657623;18638;20311.898440507703;20633.896830515903;21634.24280150187;21646.783532164634;2124.7662757096705;2143.228437762404;2370.158593699229;2457.6313552967017;2568.0883286433236;13565.186088834604;13695.794563081561;13774;14028.158310501403;14046.648833779134;1810.809864964173;1840.3650740494509;1840.3650740494643;1856.2760523395752;1913.808619138075;63953.36046639567;65560.67219663919;65788;66694.66652666706;66951.99572006472;8780.560971951378;9256.768580785534;9463.621455141854;9729.151461222345;10153.949230253802;12404.379781010914;13183.472661093449;13186.538471153464;13478.663033424244;13555.864441355658;7060.964695176594;7331.120265568059;7384;7434;7434.479586428898;6904.344087311697;6932.549384290005;7017.087778588751;7033.156021277479;7037.437005039585;6194.4115309045565;6280.780172693934;6394.552381333262;6425.935740642533;6528.836779080466;1790.7940586832422;1796.6676164909534;1833.2850188426523;1837.5681714797013;1884.9246030158915;874.4228808986039;955.6607404371487;979.0747743382435;980.2207245240052;1020.1430798129607;16618;16738.953914403966;18038;18244.908775456304;18441.5389615258;849.5030407211726;853.2406158518924;890.6749036601723;937.3438592984937;977.6871401151706;9474.336796424323;10017.849732254022;10123.797524049478;10169.186465082776;10230.846537301946;414506;417847;419441;420269;423345;80601;83410.58294708609;83445.58277208575;83861;85857.57071214604;953.4708236928572;969.8351280282369;975.3903810118676;980.6959842448721;986.1617625018677;16312.755308670143;16384.26270817792;16400.83599163993;16527.91736041312;16668.833311666727;23416.882915585313;27442.862785685942;33887.322253555285;35563;36990.260194796494;47477.287840681725;51679.2248116271;51692.22461663153;52030.73984630052;52048;7561.848763024819;7573.2805383488485;7879.527228366316;7893.565853878092;8016.318612917968;11286.774264514828;11460.656180314603;11500.654980350602;11753.764924701629;12044;10232.795344093225;10238.590456381959;10277.126444252022;10316.226283028653;10614.257002009785;9909;10083.949580252345;10293;10342.482875856327;10462.738431538968;9860.654877079554;10332.121769649664;10342.172626189737;10432.374057556572;10597.682069537617;7328.926710732824;7626.275503827128;7725.459217854696;7727.92272077272;7754.224577542201;9913.603455861685;10289.3311934724;10330.948345258526;10360.637377691744;10423.166146708094 16:51:19 INFO - PROCESS | 16457 | |3;mootools.html;1398.6713122416227;1428.6428392901776;1535.8080239970004;1682.32706917233;1741.399217270042;7636.389088872891;8066.153053929341;8154.28759960409;8159.836803263931;8169.591520423985;6535.215774107102;7527.096748390187;7782.571958542278;7813.726519571816;8000.839983200332;2203.3389983005113;2231.8102961248233;2231.933042008734;2235.284708893155;2237.284069097889;2210.745764237109;2239.0484044281197;2288.496530763238;2293.3005433342823;2294.9541009179807;1870.5230166307572;2128.819050380712;2190.8247340212743;2193.1008286602496;2214.71208742864;2127.8297736181066;2147.8389120815923;2165.902534385956;2167.5448155887284;2183.4868805830615;2159.6544552871464;2160.438286045624;2165.2313428732887;2167.2847960173103;2170.8588941718895;8004.119546849842;8073.313768329699;8121.675132994673;8123.390745694097;8138.715144969898;4470.619997300233;4585.747783871886;4589.839355622537;4595.793189306456;4608.9308660370125;2892.840893750843;2951.7786166037417;3020.879164833404;3039.741621962136;3046.36026434449;959.7792507723262;969.6945462179391;981.0385821894583;988.7676396046924;989.7129832348683;10952.069074128709;10978.615748448847;10990.945045274802;10992.17558683102;10995.505202265836;4244.830206791694;4298.720583162084;4417.580329868657;4446.844360447433;4448.621867141264;8278.089410164877;8296.336293096538;8321.916780832114;8348.540830254395;8358.45670031446;4774.976125119352;4779.761011949458;4779.880502987384;4780.521947805191;4783.1390349737385;4345.760983145894;4375.16871794358;4381.561843815657;4415.448068991376;4417.469903611587;3405.250844814137;3421.4354631486026;3470.5314782504543;3495.4232551629207;3527.7530572859655;939.2345238630494;1017.0693815159046;1023.5036007536339;1030.6547306652205;1036.4713995862014;690.8790961581803;730.5981710059446;805.3396215103676;819.6967122164838;824.2416976381623;5008.949910500921;5035.471275516092;5103.923441148386;5186.118359878754;5449.945500545023;806.4193780478047;846.5724808971553;858.8153546987428;861.793169639282;912.6486302773347;3587.51568538244;3657.04916721649;3669.7798132112157;3716.7212459065136;3788.2802267569073;9682.56428460707;10066.44634545107;10167.135793457492;10169.69490915259;10169.694909152737;9867.950660246654;10054.095131438205;10059.396436213705;10075.093241608143;10087.394756314645;7447.962760186273;7660.540367577852;7666.65500052499;7671.5397076174495;7672.693092276247;4832.226843704991;5052.267421223828;5054.7978080876355;5184.222366645034;5189.766460509361;9080.545972701206;9249.537523123683;9269.953650231706;9376.29677774156;9391.295652826202;9214.631414743604;9416.10546998034;9416.62333506652;9560.378575392577;9592.08875156859;6450.8064758057335;6456.903146452901;6546.574472659262;6581.703823328057;6584.934150658623;7732.110807257012;7760.379169666526;7776.883346749916;7778.49439786412;7801.843963120819;6835.7949261522235;6864.4165245954655;6974.825629359307;6987.475939304471;7300.598467084149;6927.653617319214;6967.79096627102;7111.217766045835;7157.57054576727;7163.283671632795;6450.612963222222;6497.870042599027;6569.835754105994;6584.275729669636;6587.440067594116;4304.246756817482;4359.237133501688;4392.407025051577;4405.889852753707;4450.732956022649;4451.065276292015;4477.462704475483;4499.392582001388;4526.773661316987;4555.20283950314 16:51:19 INFO - PROCESS | 16457 | |4;prototype.html;293.31218293102665;299.9505081661526;306.37958134777074;309.6021612228287;313.8462153544764;4644.837430689927;4761.595329377833;4848.927266091011;4878.902421951559;4879.19446318367;4672.275797251423;4823.13183626947;4827.686200396972;4831.830885918994;4845.898624880079;3801;3853.9807300963457;3854.672352850011;3857.189990102082;3865.61343865614;3650.908727281813;3722.3858063419502;3830.712696547756;3868.86458973936;3880.6701430378453;3845.8653947111857;3857.264231096004;3858;3858.4212368144863;3869.7484663496784;3830.942535861964;3831.5210598675167;3838.616138386153;3841.8655347063;3858.189780146173;3415.8975230743117;3421.349943510725;3428.7599868009247;3432.3821712091813;3432.5194472773833;4563.361129441881;4740.786664600067;4744.122337367598;4748.881277968044;4748.9525104748855;4379.189849877783;4388.780560971939;4392.868213953555;4398.868033958986;4398.890027749332;5038.5969122470115;5062.696238225718;5069.366329208849;5071.264666623356;5074.695518268879;3587.910302242439;3715.145516531213;3716.9070773230615;3749.606291339425;3765.830537625786;5304.17785243284;5325.653832500914;5342.076852333196;5351.438098999627;5354.384245811742;3783.8837542924903;3814.8664796731982;3845.0002999220405;3851.614838516182;3858.8070596470625;3800.4489349044234;3871.9806400967814;3874.3800991841736;3881.9029524261555;3914.706397020235;4333.678228140448;4357.9564204358185;4364.869053928387;4368.907773056736;4402.449693788277;5069.163588008011;5078.898422031539;5083.466236045162;5104.719240441737;5114.130597798383;1262.0912942681255;1293.5666551705285;1332.8600496947874;1345.6568575013505;1349.4062612450687;910.1626503616689;919.6183583812776;929.7257309093699;930.7486978515898;932.2122806228726;4445.777711114431;4452.710573812692;4464.241079016575;4472.888177795516;4474.507804141542;5177.171652535576;5177.508136727005;5180.6891586504935;5186.962607478519;5191.987562425291;4339.956600433955;4456.376107344974;4479.417675702203;4482.439695038121;4490.2142125127975;2422.49127683185;2445.474223042019;2447.9755202447745;2460;2472.357187131359;4344.326629372453;4378.343248512749;4389.692721509464;4392.2752745797225;4417.712848664826;4698.671093023524;4726.929096063562;4732.81679580105;4735.644826638016;4759.381280433521;4123.9381409278885;4345.391645169679;4365.541618130114;4375.693701440996;4420.9336859947125;4552;4571.657125715518;4593.047954619206;4601.838935637236;4629.4676112247525;4494.763939916549;4518.254488009442;4532;4540.250858608292;4551.431071116111;4080.6123418275215;4346.760928148982;4352.477702675699;4372.191144638252;4374.103308821747;3463.1688394785574;3463.4977928200774;3463.9826800865835;3480.3909315869114;3482.7014170647253;4594.494605593315;4600.3329517219145;4607.7191790064135;4612.354270402214;5053.216751403538;4915.11527924977;5137.614678899023;5139.1263485206855;5140.6096165283325;5150.716710580954;5080.593552515716;5134.5635620972635;5140.892234183967;5181.1969144782615;5181.896362072813;4865.416150061874;5032.3206367139965;5065.848024559269;5076.314697515788;5084.618653600921;5135.897282054413;5151.459096794782;5157.716325602128;5164.32863727728;5165.483451654804;5047.545720885138;5050.393952725696;5066.72133032672;5088.949110508847;5097.980403919305 16:51:19 INFO - PROCESS | 16457 | |5;yui.html;577.3706659740882;579.7912751409494;651.5797310734575;702.483674499243;745.5452174173753;12862.935685321572;13307.93346033271;13363.933180334108;13392.263425511594;13395.933020334896;11591.724741273882;12726.300053497078;12900.290484023375;12920.418581163847;12950.417231224594;612.5620181570176;736.7384578474645;749.3031480722939;759.5784339691496;769.0579040675157;767.9193684663114;769.3076231391736;771.8147644565292;774.2605811450052;774.7598244544209;645.132297060453;831.7255305749088;833.8415701016818;834.1950018232404;834.2200042959852;803.935685145187;817.8241678039191;819.2872201184948;819.6967122164777;820.4461988158029;597.7758340622266;598.5839841310291;598.8951933411636;599.9520038396918;600.3516202501331;12638.74722505554;12675.619731408073;12690.619281421572;12706.936465317614;12715;12182.51269949201;12288.385580720927;12551.811722824164;12601.30692811895;12844.807327890088;11646.708832279177;11791.292522448595;11863.762724745544;11897.821532677017;12163.2702037878;4115.300398932189;4118.876433706993;4182.184474027566;4234.745915245065;4241.1941731071;59634.10548841771;60010.69994650043;61211.38788612146;61436;61520.384796152364;10182.847257291147;10233.079022887976;10244.282900197064;10245.129164020993;10362.430066346424;18439.446816595257;18489.5377615558;18552.81447185511;18558.44324670262;18572;6828.317168283232;6887.311268873073;6919.792406227822;6939.028536004963;7032.824179395454;3694;3713.6843368313457;3750.062484378905;3776.0371105368504;3797.6771974382486;2790.079273839628;2901.5937768712397;2902.4775540402507;2904.549794781812;2905.7675385969073;477.0316257996273;484.36306257271576;485.75469387958856;487.4418790484875;487.94388645305537;433.5036383341057;523.1159340714181;524.7009204753331;528.9814856480082;543.4321134414546;7173.569585824867;7319.963400182965;7336.559806411632;7441.330280274801;7865.646045927947;471.9764011799396;496.4142312071718;509.93115929349807;518.8547206782107;531.4021725558746;3373.6963673269524;3506.8246587670515;3625.3111908737255;3650.3429382711365;3703;51707.48292517102;52281;60595.697021514614;83339.5833020831;87918;82825.58587207146;83016.58491707624;83418.58290708507;83726;83916.58041709752;43696;45661.771691142;72411;72982.2701772986;74316.25683743201;6928.653567321715;6988.252936765809;7028.191757947901;7101.467389945671;7201.207867134715;43753;43924.780376097915;45428.54571454243;45663.771681141385;46047.76976115098;57060.71469642625;62943.6852815733;65312.346876530624;65943.67028164829;66064.6696766513;9320.300977426585;9620.037996200324;9679.177269932135;11207.71980700489;11528.481218345027;13324.866751332362;13460.25968571738;13541.72916541644;13895.444182232597;14143.858561414254;12363.258204507758;12827.358632068546;12860.356982151043;12976.416061277454;13165.670858228621;11883.287002779862;11999.40002999829;12156.331401772633;12660;13289.468421263042;11255.718607034889;11351;11363.318200907972;11594.304341739522;11818.113641476752;9927.305088643725;10476.319039262424;10501.632442864464;10786.946065269623;10895.564177432814;9764.9023509764;9880.341197387152;9887.69885099955;9916.157126644315;9939.801203976025 16:51:19 INFO - PROCESS | 16457 | __end_tp_report 16:51:19 INFO - PROCESS | 16457 | __start_cc_report 16:51:19 INFO - PROCESS | 16457 | _x_x_mozilla_cycle_collect,1063 16:51:19 INFO - PROCESS | 16457 | __end_cc_report 16:51:19 INFO - PROCESS | 16457 | __startTimestamp1467762679190__endTimestamp 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | ------- Summary: start ------- 16:51:19 INFO - PROCESS | 16457 | Number of tests: 6 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#0] dojo.html Cycles:165 Average:4619.15 Median:4753.55 stddev:487.29 (10.3%) stddev-sans-first:461.51 16:51:19 INFO - PROCESS | 16457 | Values: 2570.3 2587.9 2603.8 2619.4 2630.0 4564.6 4571.5 4578.5 4605.9 4640.3 4582.6 4588.4 4596.6 4597.7 4620.5 4080.7 4217.2 4286.8 4314.3 4441.1 4324.0 4397.9 4399.3 4409.1 4442.6 4230.3 4242.2 4267.2 4280.1 4331.9 4238.3 4251.1 4289.7 4303.2 4307.2 4293.2 4297.0 4297.8 4320.3 4337.1 4570.6 4643.7 4696.7 4726.9 4743.2 4996.2 5021.8 5053.8 5055.6 5069.4 4890.7 4921.5 5036.2 5081.1 5096.4 4615.8 4700.1 4706.6 4706.8 4708.5 5105.7 5145.9 5147.4 5149.2 5152.6 4786.8 4936.8 4938.4 4940.1 4940.6 4948.3 5002.8 5012.2 5080.6 5085.3 4968.4 4969.4 4973.8 4975.2 5026.2 4825.8 4908.7 4955.5 4966.9 4968.1 4193.4 4196.7 4199.0 4221.7 4242.2 3680.2 3694.6 3696.7 3726.4 3743.9 4267.7 4288.1 4318.6 4368.3 4374.2 4977.6 5007.4 5039.6 5040.2 5087.7 4342.5 4349.8 4353.1 4355.5 4393.4 4417.4 4422.9 4434.0 4444.4 4482.6 4724.7 4730.0 4753.5 4780.5 4802.7 5030.8 5049.8 5050.1 5054.5 5072.4 4580.9 4619.8 4629.6 4756.5 4799.3 4857.2 4880.3 4883.9 4894.6 4918.6 4967.6 4982.8 4985.1 4986.6 5004.9 4741.1 4876.2 4920.7 4921.2 4922.0 4866.8 4893.5 4906.5 4906.9 4957.0 4842.7 4854.0 4860.7 4901.9 4908.8 4719.4 4841.9 4865.7 4892.2 4898.2 4780.4 4788.7 4788.8 4789.8 4847.9 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#1] ext.html Cycles:180 Average:45582.14 Median:3515.14 stddev:77332.05 (2200.0%) stddev-sans-first:77475.08 16:51:19 INFO - PROCESS | 16457 | Values: 553.8 577.8 579.4 588.9 596.0 27510.2 30231.7 30254.1 40036.6 40849.2 42305.4 42736.6 42773.6 42784.0 42946.1 7509.0 7947.9 8096.2 8103.2 8105.8 7515.0 7768.3 7776.2 7777.5 10464.1 10149.8 10164.9 10169.8 10174.3 10216.5 10330.6 10443.5 10451.4 10470.6 10502.6 7573.5 10048.7 10356.3 10372.8 10382.7 39323.4 39576.0 39712.4 39775.0 39814.0 3052.6 3262.7 3263.2 3519.6 3665.6 30029.1 30147.4 30227.4 30849.8 30939.2 3503.8 3510.7 3531.5 3532.1 3581.5 246278.0 246684.0 246796.0 246833.0 246914.0 53182.7 53694.5 53762.5 53791.7 53877.0 56448.0 56682.7 56838.4 56953.0 57047.0 14211.7 14620.3 14670.0 14680.3 14718.9 11619.9 11662.1 11694.8 11695.8 11713.8 14626.3 16199.0 16865.9 16937.2 17367.6 5885.1 5887.6 5963.7 6010.9 6023.3 794.3 799.5 813.1 922.5 1021.2 27823.3 28108.3 28259.0 28617.1 28674.0 962.5 965.9 970.4 996.1 1003.7 13837.2 13856.7 13997.9 14190.7 14240.9 358657.0 361423.0 361724.0 361829.0 362121.0 49655.5 49692.5 49701.8 49811.5 49821.8 1079.8 1119.8 1169.0 1222.0 1235.9 28708.7 29687.0 29774.0 30507.7 30640.2 42749.1 47007.3 48477.0 48486.5 53138.7 263847.0 264217.0 264245.0 264726.0 265320.0 22148.2 22747.9 22788.4 25122.6 25505.7 34341.7 35644.3 43002.4 44899.0 48646.5 36046.0 37812.2 38209.7 38462.2 38755.6 36760.4 37045.0 37420.0 37476.8 37666.6 35956.6 36445.0 36613.3 36684.0 36767.4 32596.7 33422.5 34279.0 34322.7 34412.3 25295.4 25335.7 25644.7 25724.2 26286.7 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#2] jquery.html Cycles:180 Average:25300.35 Median:392.25 stddev:69037.56 (17600.5%) stddev-sans-first:69205.81 16:51:19 INFO - PROCESS | 16457 | Values: 352.2 363.8 370.6 385.9 398.6 13033.9 16362.9 17044.0 18181.9 18432.6 17024.0 17101.6 17231.4 17352.7 17416.2 5013.0 5204.3 5289.2 5295.3 5300.2 3773.4 5154.7 5200.2 5822.3 6133.4 6157.4 6178.5 6180.5 6186.5 6193.5 5928.1 6100.2 6194.8 6197.8 6206.2 3442.2 3619.9 3622.0 5430.6 5434.8 18638.0 20311.9 20633.9 21634.2 21646.8 2124.8 2143.2 2370.2 2457.6 2568.1 13565.2 13695.8 13774.0 14028.2 14046.6 1810.8 1840.4 1840.4 1856.3 1913.8 63953.4 65560.7 65788.0 66694.7 66952.0 8780.6 9256.8 9463.6 9729.2 10153.9 12404.4 13183.5 13186.5 13478.7 13555.9 7061.0 7331.1 7384.0 7434.0 7434.5 6904.3 6932.5 7017.1 7033.2 7037.4 6194.4 6280.8 6394.6 6425.9 6528.8 1790.8 1796.7 1833.3 1837.6 1884.9 874.4 955.7 979.1 980.2 1020.1 16618.0 16739.0 18038.0 18244.9 18441.5 849.5 853.2 890.7 937.3 977.7 9474.3 10017.8 10123.8 10169.2 10230.8 414506.0 417847.0 419441.0 420269.0 423345.0 80601.0 83410.6 83445.6 83861.0 85857.6 953.5 969.8 975.4 980.7 986.2 16312.8 16384.3 16400.8 16527.9 16668.8 23416.9 27442.9 33887.3 35563.0 36990.3 47477.3 51679.2 51692.2 52030.7 52048.0 7561.8 7573.3 7879.5 7893.6 8016.3 11286.8 11460.7 11500.7 11753.8 12044.0 10232.8 10238.6 10277.1 10316.2 10614.3 9909.0 10083.9 10293.0 10342.5 10462.7 9860.7 10332.1 10342.2 10432.4 10597.7 7328.9 7626.3 7725.5 7727.9 7754.2 9913.6 10289.3 10330.9 10360.6 10423.2 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#3] mootools.html Cycles:180 Average:5214.83 Median:4781.83 stddev:2968.62 (62.1%) stddev-sans-first:2963.10 16:51:19 INFO - PROCESS | 16457 | Values: 1398.7 1428.6 1535.8 1682.3 1741.4 7636.4 8066.2 8154.3 8159.8 8169.6 6535.2 7527.1 7782.6 7813.7 8000.8 2203.3 2231.8 2231.9 2235.3 2237.3 2210.7 2239.0 2288.5 2293.3 2295.0 1870.5 2128.8 2190.8 2193.1 2214.7 2127.8 2147.8 2165.9 2167.5 2183.5 2159.7 2160.4 2165.2 2167.3 2170.9 8004.1 8073.3 8121.7 8123.4 8138.7 4470.6 4585.7 4589.8 4595.8 4608.9 2892.8 2951.8 3020.9 3039.7 3046.4 959.8 969.7 981.0 988.8 989.7 10952.1 10978.6 10990.9 10992.2 10995.5 4244.8 4298.7 4417.6 4446.8 4448.6 8278.1 8296.3 8321.9 8348.5 8358.5 4775.0 4779.8 4779.9 4780.5 4783.1 4345.8 4375.2 4381.6 4415.4 4417.5 3405.3 3421.4 3470.5 3495.4 3527.8 939.2 1017.1 1023.5 1030.7 1036.5 690.9 730.6 805.3 819.7 824.2 5008.9 5035.5 5103.9 5186.1 5449.9 806.4 846.6 858.8 861.8 912.6 3587.5 3657.0 3669.8 3716.7 3788.3 9682.6 10066.4 10167.1 10169.7 10169.7 9868.0 10054.1 10059.4 10075.1 10087.4 7448.0 7660.5 7666.7 7671.5 7672.7 4832.2 5052.3 5054.8 5184.2 5189.8 9080.5 9249.5 9270.0 9376.3 9391.3 9214.6 9416.1 9416.6 9560.4 9592.1 6450.8 6456.9 6546.6 6581.7 6584.9 7732.1 7760.4 7776.9 7778.5 7801.8 6835.8 6864.4 6974.8 6987.5 7300.6 6927.7 6967.8 7111.2 7157.6 7163.3 6450.6 6497.9 6569.8 6584.3 6587.4 4304.2 4359.2 4392.4 4405.9 4450.7 4451.1 4477.5 4499.4 4526.8 4555.2 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#4] prototype.html Cycles:180 Average:4120.42 Median:4473.70 stddev:1172.13 (26.2%) stddev-sans-first:1139.67 16:51:19 INFO - PROCESS | 16457 | Values: 293.3 300.0 306.4 309.6 313.8 4644.8 4761.6 4848.9 4878.9 4879.2 4672.3 4823.1 4827.7 4831.8 4845.9 3801.0 3854.0 3854.7 3857.2 3865.6 3650.9 3722.4 3830.7 3868.9 3880.7 3845.9 3857.3 3858.0 3858.4 3869.7 3830.9 3831.5 3838.6 3841.9 3858.2 3415.9 3421.3 3428.8 3432.4 3432.5 4563.4 4740.8 4744.1 4748.9 4749.0 4379.2 4388.8 4392.9 4398.9 4398.9 5038.6 5062.7 5069.4 5071.3 5074.7 3587.9 3715.1 3716.9 3749.6 3765.8 5304.2 5325.7 5342.1 5351.4 5354.4 3783.9 3814.9 3845.0 3851.6 3858.8 3800.4 3872.0 3874.4 3881.9 3914.7 4333.7 4358.0 4364.9 4368.9 4402.4 5069.2 5078.9 5083.5 5104.7 5114.1 1262.1 1293.6 1332.9 1345.7 1349.4 910.2 919.6 929.7 930.7 932.2 4445.8 4452.7 4464.2 4472.9 4474.5 5177.2 5177.5 5180.7 5187.0 5192.0 4340.0 4456.4 4479.4 4482.4 4490.2 2422.5 2445.5 2448.0 2460.0 2472.4 4344.3 4378.3 4389.7 4392.3 4417.7 4698.7 4726.9 4732.8 4735.6 4759.4 4123.9 4345.4 4365.5 4375.7 4420.9 4552.0 4571.7 4593.0 4601.8 4629.5 4494.8 4518.3 4532.0 4540.3 4551.4 4080.6 4346.8 4352.5 4372.2 4374.1 3463.2 3463.5 3464.0 3480.4 3482.7 4594.5 4600.3 4607.7 4612.4 5053.2 4915.1 5137.6 5139.1 5140.6 5150.7 5080.6 5134.6 5140.9 5181.2 5181.9 4865.4 5032.3 5065.8 5076.3 5084.6 5135.9 5151.5 5157.7 5164.3 5165.5 5047.5 5050.4 5066.7 5088.9 5098.0 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - PROCESS | 16457 | [#5] yui.html Cycles:180 Average:16554.24 Median:480.70 stddev:22431.27 (4666.4%) stddev-sans-first:22462.12 16:51:19 INFO - PROCESS | 16457 | Values: 577.4 579.8 651.6 702.5 745.5 12862.9 13307.9 13363.9 13392.3 13395.9 11591.7 12726.3 12900.3 12920.4 12950.4 612.6 736.7 749.3 759.6 769.1 767.9 769.3 771.8 774.3 774.8 645.1 831.7 833.8 834.2 834.2 803.9 817.8 819.3 819.7 820.4 597.8 598.6 598.9 600.0 600.4 12638.7 12675.6 12690.6 12706.9 12715.0 12182.5 12288.4 12551.8 12601.3 12844.8 11646.7 11791.3 11863.8 11897.8 12163.3 4115.3 4118.9 4182.2 4234.7 4241.2 59634.1 60010.7 61211.4 61436.0 61520.4 10182.8 10233.1 10244.3 10245.1 10362.4 18439.4 18489.5 18552.8 18558.4 18572.0 6828.3 6887.3 6919.8 6939.0 7032.8 3694.0 3713.7 3750.1 3776.0 3797.7 2790.1 2901.6 2902.5 2904.5 2905.8 477.0 484.4 485.8 487.4 487.9 433.5 523.1 524.7 529.0 543.4 7173.6 7320.0 7336.6 7441.3 7865.6 472.0 496.4 509.9 518.9 531.4 3373.7 3506.8 3625.3 3650.3 3703.0 51707.5 52281.0 60595.7 83339.6 87918.0 82825.6 83016.6 83418.6 83726.0 83916.6 43696.0 45661.8 72411.0 72982.3 74316.3 6928.7 6988.3 7028.2 7101.5 7201.2 43753.0 43924.8 45428.5 45663.8 46047.8 57060.7 62943.7 65312.3 65943.7 66064.7 9320.3 9620.0 9679.2 11207.7 11528.5 13324.9 13460.3 13541.7 13895.4 14143.9 12363.3 12827.4 12860.4 12976.4 13165.7 11883.3 11999.4 12156.3 12660.0 13289.5 11255.7 11351.0 11363.3 11594.3 11818.1 9927.3 10476.3 10501.6 10786.9 10895.6 9764.9 9880.3 9887.7 9916.2 9939.8 16:51:19 INFO - PROCESS | 16457 | -------- Summary: end -------- 16:51:19 INFO - PROCESS | 16457 | 16:51:19 INFO - TEST-INFO | 16457: exit 0 16:51:19 INFO - TEST-OK | dromaeo_css | took 1120007ms 16:51:19 INFO - TEST-START | kraken 16:51:19 INFO - operating with platform_type : linux_ 16:51:19 INFO - Initialising browser for kraken test... 16:51:19 INFO - TEST-INFO | started process 16645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsmQV4z/profile http://localhost:39569/getInfo.html) 16:51:24 INFO - PROCESS | 16645 | __metrics Screen width/height:1600/1200 16:51:24 INFO - PROCESS | 16645 | colorDepth:24 16:51:24 INFO - PROCESS | 16645 | Browser inner width/height: 1024/697 16:51:24 INFO - PROCESS | 16645 | __metrics 16:51:25 INFO - PROCESS | 16645 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:26 INFO - TEST-INFO | 16645: exit 0 16:51:26 INFO - Browser initialized. 16:51:26 INFO - Running cycle 1/1 for kraken test... 16:51:26 INFO - TEST-INFO | started process 16723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsmQV4z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:38 INFO - PROCESS | 16723 | RSS: Main: 159506432 16:51:38 INFO - PROCESS | 16723 | 16:51:40 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39569/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:41 INFO - PROCESS | 16723 | RSS: Main: 216670208 16:51:41 INFO - PROCESS | 16723 | 16:51:44 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39569/tests/kraken/driver.html?testName=audio-dft) 16:51:45 INFO - PROCESS | 16723 | RSS: Main: 204251136 16:51:45 INFO - PROCESS | 16723 | 16:51:48 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39569/tests/kraken/driver.html?testName=audio-fft) 16:51:49 INFO - PROCESS | 16723 | RSS: Main: 270934016 16:51:49 INFO - PROCESS | 16723 | 16:51:51 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39569/tests/kraken/driver.html?testName=audio-oscillator) 16:51:51 INFO - PROCESS | 16723 | RSS: Main: 181694464 16:51:51 INFO - PROCESS | 16723 | 16:51:54 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39569/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:54 INFO - PROCESS | 16723 | RSS: Main: 186077184 16:51:54 INFO - PROCESS | 16723 | 16:51:57 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39569/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:58 INFO - PROCESS | 16723 | RSS: Main: 222027776 16:51:58 INFO - PROCESS | 16723 | 16:52:02 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39569/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:02 INFO - PROCESS | 16723 | RSS: Main: 217546752 16:52:02 INFO - PROCESS | 16723 | 16:52:05 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39569/tests/kraken/driver.html?testName=json-parse-financial) 16:52:06 INFO - PROCESS | 16723 | RSS: Main: 221192192 16:52:06 INFO - PROCESS | 16723 | 16:52:07 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39569/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:08 INFO - PROCESS | 16723 | RSS: Main: 208429056 16:52:08 INFO - PROCESS | 16723 | 16:52:10 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:11 INFO - PROCESS | 16723 | RSS: Main: 212713472 16:52:11 INFO - PROCESS | 16723 | 16:52:13 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:13 INFO - PROCESS | 16723 | RSS: Main: 210857984 16:52:13 INFO - PROCESS | 16723 | 16:52:16 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:17 INFO - PROCESS | 16723 | RSS: Main: 226299904 16:52:17 INFO - PROCESS | 16723 | 16:52:20 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:20 INFO - PROCESS | 16723 | RSS: Main: 225288192 16:52:20 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | Cycle 1(1): loaded http://localhost:39569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39569/tests/kraken/driver.html?testName=ai-astar) 16:52:22 INFO - PROCESS | 16723 | RSS: Main: 268812288 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | __start_tp_report 16:52:22 INFO - PROCESS | 16723 | _x_x_mozilla_page_load 16:52:22 INFO - PROCESS | 16723 | _x_x_mozilla_page_load_details 16:52:22 INFO - PROCESS | 16723 | |i|pagename|runs| 16:52:22 INFO - PROCESS | 16723 | |0;ai-astar;103;115;112;113;113;111;113;111;113;111 16:52:22 INFO - PROCESS | 16723 | |1;audio-beat-detection;151;124;124;176;123;124;146;123;172;123 16:52:22 INFO - PROCESS | 16723 | |2;audio-dft;161;162;163;160;162;162;161;162;160;163 16:52:22 INFO - PROCESS | 16723 | |3;audio-fft;76;76;76;135;77;76;75;75;98;76 16:52:22 INFO - PROCESS | 16723 | |4;audio-oscillator;85;86;119;85;86;86;115;84;85;129 16:52:22 INFO - PROCESS | 16723 | |5;imaging-gaussian-blur;117;117;117;117;117;116;118;117;117;116 16:52:22 INFO - PROCESS | 16723 | |6;imaging-darkroom;147;146;147;146;146;146;147;147;146;146 16:52:22 INFO - PROCESS | 16723 | |7;imaging-desaturate;94;94;94;95;95;95;95;95;94;95 16:52:22 INFO - PROCESS | 16723 | |8;json-parse-financial;65;64;63;64;66;64;66;63;63;64 16:52:22 INFO - PROCESS | 16723 | |9;json-stringify-tinderbox;69;65;65;66;65;65;66;65;65;65 16:52:22 INFO - PROCESS | 16723 | |10;stanford-crypto-aes;78;80;78;88;80;80;78;78;80;78 16:52:22 INFO - PROCESS | 16723 | |11;stanford-crypto-ccm;128;126;127;127;128;129;131;131;177;132 16:52:22 INFO - PROCESS | 16723 | |12;stanford-crypto-pbkdf2;170;169;170;166;169;168;170;170;169;166 16:52:22 INFO - PROCESS | 16723 | |13;stanford-crypto-sha256-iterative;67;71;70;67;66;65;66;67;67;65 16:52:22 INFO - PROCESS | 16723 | __end_tp_report 16:52:22 INFO - PROCESS | 16723 | __start_cc_report 16:52:22 INFO - PROCESS | 16723 | _x_x_mozilla_cycle_collect,1882 16:52:22 INFO - PROCESS | 16723 | __end_cc_report 16:52:22 INFO - PROCESS | 16723 | __startTimestamp1467762742689__endTimestamp 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | ------- Summary: start ------- 16:52:22 INFO - PROCESS | 16723 | Number of tests: 14 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.24 (2.9%) stddev-sans-first:1.33 16:52:22 INFO - PROCESS | 16723 | Values: 103.0 115.0 112.0 113.0 113.0 111.0 113.0 111.0 113.0 111.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:135.00 stddev:21.32 (15.8%) stddev-sans-first:22.14 16:52:22 INFO - PROCESS | 16723 | Values: 151.0 124.0 124.0 176.0 123.0 124.0 146.0 123.0 172.0 123.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 16:52:22 INFO - PROCESS | 16723 | Values: 161.0 162.0 163.0 160.0 162.0 162.0 161.0 162.0 160.0 163.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#3] audio-fft Cycles:10 Average:84.00 Median:76.00 stddev:19.23 (25.3%) stddev-sans-first:20.18 16:52:22 INFO - PROCESS | 16723 | Values: 76.0 76.0 76.0 135.0 77.0 76.0 75.0 75.0 98.0 76.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:85.00 stddev:17.59 (20.7%) stddev-sans-first:18.21 16:52:22 INFO - PROCESS | 16723 | Values: 85.0 86.0 119.0 85.0 86.0 86.0 115.0 84.0 85.0 129.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:52:22 INFO - PROCESS | 16723 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 118.0 117.0 117.0 116.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:52:22 INFO - PROCESS | 16723 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:52:22 INFO - PROCESS | 16723 | Values: 94.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:1.14 (1.8%) stddev-sans-first:1.17 16:52:22 INFO - PROCESS | 16723 | Values: 65.0 64.0 63.0 64.0 66.0 64.0 66.0 63.0 63.0 64.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:0.44 16:52:22 INFO - PROCESS | 16723 | Values: 69.0 65.0 65.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:3.05 (3.8%) stddev-sans-first:3.16 16:52:22 INFO - PROCESS | 16723 | Values: 78.0 80.0 78.0 88.0 80.0 80.0 78.0 78.0 80.0 78.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:130.00 stddev:15.38 (11.8%) stddev-sans-first:16.18 16:52:22 INFO - PROCESS | 16723 | Values: 128.0 126.0 127.0 127.0 128.0 129.0 131.0 131.0 177.0 132.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:169.50 stddev:1.57 (0.9%) stddev-sans-first:1.59 16:52:22 INFO - PROCESS | 16723 | Values: 170.0 169.0 170.0 166.0 169.0 168.0 170.0 170.0 169.0 166.0 16:52:22 INFO - PROCESS | 16723 | 16:52:22 INFO - PROCESS | 16723 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.97 (2.9%) stddev-sans-first:2.09 16:52:22 INFO - PROCESS | 16723 | Values: 67.0 71.0 70.0 67.0 66.0 65.0 66.0 67.0 67.0 65.0 16:52:22 INFO - PROCESS | 16723 | -------- Summary: end -------- 16:52:22 INFO - PROCESS | 16723 | 16:52:23 INFO - TEST-INFO | 16723: exit 0 16:52:23 INFO - TEST-OK | kraken | took 63803ms 16:52:23 INFO - SUITE-END | took 1183s 16:52:23 INFO - Completed test suite (00:19:43) 16:52:23 INFO - javascript benchmark 16:52:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2570.280321509978, 2587.909423170189, 2603.7786788123008, 2619.4499155177414, 2629.9737002629968, 4564.612007979325, 4571.51999040101, 4578.450585929689, 4605.907881842361, 4640.280756482742, 4582.633389328854, 4588.403507544023, 4596.632269418446, 4597.655175861815, 4620.537946205378, 4080.7347522411096, 4217.21981433435, 4286.7856607169515, 4314.331278651814, 4441.133978874121, 4323.978380108111, 4397.934030989538, 4399.2521271383785, 4409.118176364724, 4442.62237709795, 4230.302000169968, 4242.215190189794, 4267.167902259061, 4280.079782846703, 4331.891702707426, 4238.300680387732, 4251.06476575153, 4289.742615443052, 4303.225419424502, 4307.224699554079, 4293.205756934977, 4296.978515107405, 4297.828086876521, 4320.308750599889, 4337.132573485315, 4570.611498022672, 4643.674942754009, 4696.671233013691, 4726.858194254178, 4743.241081426955, 4996.200607902754, 5021.799128034874, 5053.8231161909525, 5055.620828437882, 5069.391672999227, 4890.73100979446, 4921.483244259372, 5036.244563315496, 5081.110805609041, 5096.388433388017, 4615.792289346988, 4700.083483720641, 4706.647001474903, 4706.835260765925, 4708.505606911293, 5105.693658380509, 5145.948540514547, 5147.356580427447, 5149.201873709581, 5152.639315247897, 4786.8085276589245, 4936.827211047596, 4938.407391113089, 4940.061388336205, 4940.555350018516, 4948.282499037619, 5002.799888004512, 5012.172991456442, 5080.5681517070725, 5085.262636917626, 4968.4286307075145, 4969.378827646544, 4973.825916092992, 4975.203967365276, 5026.170681837529, 4825.831095911696, 4908.656394052382, 4955.529224723646, 4966.900661986812, 4968.056069346812, 4193.391958166048, 4196.685248606367, 4198.958010419857, 4221.68337374692, 4242.193983143193, 3680.2087551176623, 3694.556653201633, 3696.722745794076, 3726.403775395978, 3743.9064023399633, 4267.679924005712, 4288.09949910516, 4318.632916202095, 4368.344748287782, 4374.19077470669, 4977.576905962961, 5007.4491805901325, 5039.571636410946, 5040.243963405518, 5087.669301495391, 4342.478902531717, 4349.804258808361, 4353.064091220403, 4355.477342718894, 4393.428854248928, 4417.359482874966, 4422.911541769211, 4434.046679963823, 4444.444444444444, 4482.551744825557, 4724.669273150984, 4730.0, 4753.548412900769, 4780.4741478437345, 4802.663813533159, 5030.798768049237, 5049.7727602257255, 5050.090983622983, 5054.519820617035, 5072.391313042459, 4580.862574122782, 4619.815207391666, 4629.583337499641, 4756.500567440369, 4799.3041009052795, 4857.222844344888, 4880.31675565414, 4883.853484395474, 4894.559489645949, 4918.631102667243, 4967.577755890789, 4982.800687972441, 4985.102681517362, 4986.576141027908, 5004.87487812793, 4741.051789642016, 4876.195427754523, 4920.6555541111775, 4921.212605983025, 4922.0, 4866.829660961849, 4893.486183950777, 4906.484819094087, 4906.926396104132, 4956.9752151240455, 4842.685225460334, 4853.975730121327, 4860.708357498562, 4901.90196196067, 4908.828191013297, 4719.4100737407825, 4841.8547443576745, 4865.7323847188745, 4892.217245240744, 4898.24077268024, 4780.378550788375, 4788.664793464424, 4788.784504697227, 4789.8323558676675, 4847.854564363075], "value": 4587.840964351197, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.8055547812189, 577.7891069759538, 579.3858509979419, 588.861617519883, 595.9970200148998, 27510.174694759164, 30231.697683023136, 30254.09237722869, 40036.59963400365, 40849.18301633966, 42305.365419518654, 42736.572634273645, 42773.57226427735, 42784.0, 42946.06742696578, 7508.962455187744, 7947.880781788293, 8096.230858068474, 8103.189681031909, 8105.75682729519, 7514.962425187894, 7768.262015108556, 7776.222377762235, 7777.5333479991095, 10464.110550603169, 10149.797004059914, 10164.949175254113, 10169.847452288222, 10174.287799854013, 10216.540255688511, 10330.58677652893, 10443.530041148166, 10451.425171615558, 10470.581176752921, 10502.632407865687, 7573.469857110004, 10048.69853904384, 10356.275060745751, 10372.844407333896, 10382.688519344432, 39323.41014884779, 39576.0, 39712.404313935316, 39775.0, 39814.0, 3052.6031615890015, 3262.6900444457738, 3263.216827961296, 3519.560054993126, 3665.596784353719, 30029.099127026224, 30147.397052058837, 30227.395452090834, 30849.845750771103, 30939.226519337197, 3503.8248087595775, 3510.7191424685966, 3531.5408996830506, 3532.0993146747674, 3581.5164952731307, 246278.0, 246684.0, 246796.0, 246833.0, 246913.9999999982, 53182.73408632932, 53694.46305536895, 53762.46237537575, 53791.731041344545, 53877.0, 56448.0, 56682.716586416806, 56838.43161568414, 56953.0, 57047.0, 14211.715765684628, 14620.342084606218, 14670.0, 14680.339384727713, 14718.852811471963, 11619.941900290445, 11662.125340599488, 11694.824577631342, 11695.824562631567, 11713.824292635618, 14626.341814618369, 16199.0, 16865.91567042157, 16937.153142342835, 17367.56581085483, 5885.146653735184, 5887.61730487517, 5963.701814909324, 6010.909836352458, 6023.27720673522, 794.2891112454321, 799.4883274704194, 813.0812182234038, 922.5387306346827, 1021.1568119544294, 27823.30441738932, 28108.297292567848, 28259.0, 28617.14148575546, 28674.0, 962.5331714118647, 965.8551217317458, 970.4080510888352, 996.1134590214715, 1003.7490627343165, 13837.238951857544, 13856.653583660489, 13997.930010349883, 14190.716185676229, 14240.857591424161, 358657.0, 361423.0, 361724.0, 361829.0, 362121.0, 49655.503444965085, 49692.50307496951, 49701.751491242314, 49811.50188498069, 49821.75089124531, 1079.7516571188671, 1119.798436281461, 1168.9941550292194, 1221.969450763738, 1235.8764123587569, 28708.712912870604, 29687.0, 29774.0, 30507.694923050487, 30640.233994150323, 42749.14501709886, 47007.29489057735, 48477.0, 48486.5151348482, 53138.734306328224, 263847.0, 264217.0, 264245.0, 264726.0, 265320.0, 22148.2248121315, 22747.88626056859, 22788.43028924224, 25122.62316065297, 25505.744942550336, 34341.656583434844, 35644.28711425809, 43002.35496467493, 44899.0, 48646.5135348642, 36046.0, 37812.243755125295, 38209.733487833626, 38462.230755384175, 38755.61244387633, 36760.448593271656, 37045.0, 37420.0, 37476.812615936746, 37666.623333766314, 35956.640433596374, 36445.0, 36613.26773464569, 36684.0, 36767.44848827216, 32596.67403326031, 33422.49866252057, 34279.0, 34322.65677343195, 34412.31175376428, 25295.36761580975, 25335.74664253334, 25644.743552564236, 25724.228273151835, 26286.73713262843], "value": 17870.73274448526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.17590837440383, 363.8490026638944, 370.5941993517098, 385.91702783901457, 398.57949862894634, 13033.934830325847, 16362.918185409071, 17044.0, 18181.909090454563, 18432.631347373044, 17023.9785612863, 17101.572460688458, 17231.396901108463, 17352.739708904344, 17416.216270267836, 5012.997400519902, 5204.271402003704, 5289.233061206123, 5295.28513650658, 5300.178472336794, 3773.358529050054, 5154.716490593016, 5200.245964335169, 5822.330432000311, 6133.447989680928, 6157.415045570664, 6178.474829639464, 6180.536459765513, 6186.536009799261, 6193.535484838632, 5928.081147422156, 6100.23747031621, 6194.752209911598, 6197.845053873644, 6206.224221972254, 3442.208292092808, 3619.94570081449, 3622.040159357772, 5430.619856610039, 5434.809781657623, 18638.0, 20311.898440507703, 20633.896830515903, 21634.24280150187, 21646.783532164634, 2124.7662757096705, 2143.228437762404, 2370.158593699229, 2457.6313552967017, 2568.0883286433236, 13565.186088834604, 13695.794563081561, 13774.0, 14028.158310501403, 14046.648833779134, 1810.809864964173, 1840.3650740494509, 1840.3650740494643, 1856.2760523395752, 1913.808619138075, 63953.36046639567, 65560.67219663919, 65788.0, 66694.66652666706, 66951.99572006472, 8780.560971951378, 9256.768580785534, 9463.621455141854, 9729.151461222345, 10153.949230253802, 12404.379781010914, 13183.472661093449, 13186.538471153464, 13478.663033424244, 13555.864441355658, 7060.964695176594, 7331.120265568059, 7384.0, 7434.0, 7434.479586428898, 6904.344087311697, 6932.549384290005, 7017.087778588751, 7033.156021277479, 7037.437005039585, 6194.4115309045565, 6280.780172693934, 6394.552381333262, 6425.935740642533, 6528.836779080466, 1790.7940586832422, 1796.6676164909534, 1833.2850188426523, 1837.5681714797013, 1884.9246030158915, 874.4228808986039, 955.6607404371487, 979.0747743382435, 980.2207245240052, 1020.1430798129607, 16618.0, 16738.953914403966, 18038.0, 18244.908775456304, 18441.5389615258, 849.5030407211726, 853.2406158518924, 890.6749036601723, 937.3438592984937, 977.6871401151706, 9474.336796424323, 10017.849732254022, 10123.797524049478, 10169.186465082776, 10230.846537301946, 414506.0, 417847.0, 419441.0, 420269.0, 423345.0, 80601.0, 83410.58294708609, 83445.58277208575, 83861.0, 85857.57071214604, 953.4708236928572, 969.8351280282369, 975.3903810118676, 980.6959842448721, 986.1617625018677, 16312.755308670143, 16384.26270817792, 16400.83599163993, 16527.91736041312, 16668.833311666727, 23416.882915585313, 27442.862785685942, 33887.322253555285, 35563.0, 36990.260194796494, 47477.287840681725, 51679.2248116271, 51692.22461663153, 52030.73984630052, 52048.0, 7561.848763024819, 7573.2805383488485, 7879.527228366316, 7893.565853878092, 8016.318612917968, 11286.774264514828, 11460.656180314603, 11500.654980350602, 11753.764924701629, 12044.0, 10232.795344093225, 10238.590456381959, 10277.126444252022, 10316.226283028653, 10614.257002009785, 9909.0, 10083.949580252345, 10293.0, 10342.482875856327, 10462.738431538968, 9860.654877079554, 10332.121769649664, 10342.172626189737, 10432.374057556572, 10597.682069537617, 7328.926710732824, 7626.275503827128, 7725.459217854696, 7727.92272077272, 7754.224577542201, 9913.603455861685, 10289.3311934724, 10330.948345258526, 10360.637377691744, 10423.166146708094], "value": 8513.251326878835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.6713122416227, 1428.6428392901776, 1535.8080239970004, 1682.32706917233, 1741.399217270042, 7636.389088872891, 8066.153053929341, 8154.28759960409, 8159.836803263931, 8169.591520423985, 6535.215774107102, 7527.096748390187, 7782.571958542278, 7813.726519571816, 8000.839983200332, 2203.3389983005113, 2231.8102961248233, 2231.933042008734, 2235.284708893155, 2237.284069097889, 2210.745764237109, 2239.0484044281197, 2288.496530763238, 2293.3005433342823, 2294.9541009179807, 1870.5230166307572, 2128.819050380712, 2190.8247340212743, 2193.1008286602496, 2214.71208742864, 2127.8297736181066, 2147.8389120815923, 2165.902534385956, 2167.5448155887284, 2183.4868805830615, 2159.6544552871464, 2160.438286045624, 2165.2313428732887, 2167.2847960173103, 2170.8588941718895, 8004.119546849842, 8073.313768329699, 8121.675132994673, 8123.390745694097, 8138.715144969898, 4470.619997300233, 4585.747783871886, 4589.839355622537, 4595.793189306456, 4608.9308660370125, 2892.840893750843, 2951.7786166037417, 3020.879164833404, 3039.741621962136, 3046.36026434449, 959.7792507723262, 969.6945462179391, 981.0385821894583, 988.7676396046924, 989.7129832348683, 10952.069074128709, 10978.615748448847, 10990.945045274802, 10992.17558683102, 10995.505202265836, 4244.830206791694, 4298.720583162084, 4417.580329868657, 4446.844360447433, 4448.621867141264, 8278.089410164877, 8296.336293096538, 8321.916780832114, 8348.540830254395, 8358.45670031446, 4774.976125119352, 4779.761011949458, 4779.880502987384, 4780.521947805191, 4783.1390349737385, 4345.760983145894, 4375.16871794358, 4381.561843815657, 4415.448068991376, 4417.469903611587, 3405.250844814137, 3421.4354631486026, 3470.5314782504543, 3495.4232551629207, 3527.7530572859655, 939.2345238630494, 1017.0693815159046, 1023.5036007536339, 1030.6547306652205, 1036.4713995862014, 690.8790961581803, 730.5981710059446, 805.3396215103676, 819.6967122164838, 824.2416976381623, 5008.949910500921, 5035.471275516092, 5103.923441148386, 5186.118359878754, 5449.945500545023, 806.4193780478047, 846.5724808971553, 858.8153546987428, 861.793169639282, 912.6486302773347, 3587.51568538244, 3657.04916721649, 3669.7798132112157, 3716.7212459065136, 3788.2802267569073, 9682.56428460707, 10066.44634545107, 10167.135793457492, 10169.69490915259, 10169.694909152737, 9867.950660246654, 10054.095131438205, 10059.396436213705, 10075.093241608143, 10087.394756314645, 7447.962760186273, 7660.540367577852, 7666.65500052499, 7671.5397076174495, 7672.693092276247, 4832.226843704991, 5052.267421223828, 5054.7978080876355, 5184.222366645034, 5189.766460509361, 9080.545972701206, 9249.537523123683, 9269.953650231706, 9376.29677774156, 9391.295652826202, 9214.631414743604, 9416.10546998034, 9416.62333506652, 9560.378575392577, 9592.08875156859, 6450.8064758057335, 6456.903146452901, 6546.574472659262, 6581.703823328057, 6584.934150658623, 7732.110807257012, 7760.379169666526, 7776.883346749916, 7778.49439786412, 7801.843963120819, 6835.7949261522235, 6864.4165245954655, 6974.825629359307, 6987.475939304471, 7300.598467084149, 6927.653617319214, 6967.79096627102, 7111.217766045835, 7157.57054576727, 7163.283671632795, 6450.612963222222, 6497.870042599027, 6569.835754105994, 6584.275729669636, 6587.440067594116, 4304.246756817482, 4359.237133501688, 4392.407025051577, 4405.889852753707, 4450.732956022649, 4451.065276292015, 4477.462704475483, 4499.392582001388, 4526.773661316987, 4555.20283950314], "value": 4168.009509406073, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.31218293102665, 299.9505081661526, 306.37958134777074, 309.6021612228287, 313.8462153544764, 4644.837430689927, 4761.595329377833, 4848.927266091011, 4878.902421951559, 4879.19446318367, 4672.275797251423, 4823.13183626947, 4827.686200396972, 4831.830885918994, 4845.898624880079, 3801.0, 3853.9807300963457, 3854.672352850011, 3857.189990102082, 3865.61343865614, 3650.908727281813, 3722.3858063419502, 3830.712696547756, 3868.86458973936, 3880.6701430378453, 3845.8653947111857, 3857.264231096004, 3858.0, 3858.4212368144863, 3869.7484663496784, 3830.942535861964, 3831.5210598675167, 3838.616138386153, 3841.8655347063, 3858.189780146173, 3415.8975230743117, 3421.349943510725, 3428.7599868009247, 3432.3821712091813, 3432.5194472773833, 4563.361129441881, 4740.786664600067, 4744.122337367598, 4748.881277968044, 4748.9525104748855, 4379.189849877783, 4388.780560971939, 4392.868213953555, 4398.868033958986, 4398.890027749332, 5038.5969122470115, 5062.696238225718, 5069.366329208849, 5071.264666623356, 5074.695518268879, 3587.910302242439, 3715.145516531213, 3716.9070773230615, 3749.606291339425, 3765.830537625786, 5304.17785243284, 5325.653832500914, 5342.076852333196, 5351.438098999627, 5354.384245811742, 3783.8837542924903, 3814.8664796731982, 3845.0002999220405, 3851.614838516182, 3858.8070596470625, 3800.4489349044234, 3871.9806400967814, 3874.3800991841736, 3881.9029524261555, 3914.706397020235, 4333.678228140448, 4357.9564204358185, 4364.869053928387, 4368.907773056736, 4402.449693788277, 5069.163588008011, 5078.898422031539, 5083.466236045162, 5104.719240441737, 5114.130597798383, 1262.0912942681255, 1293.5666551705285, 1332.8600496947874, 1345.6568575013505, 1349.4062612450687, 910.1626503616689, 919.6183583812776, 929.7257309093699, 930.7486978515898, 932.2122806228726, 4445.777711114431, 4452.710573812692, 4464.241079016575, 4472.888177795516, 4474.507804141542, 5177.171652535576, 5177.508136727005, 5180.6891586504935, 5186.962607478519, 5191.987562425291, 4339.956600433955, 4456.376107344974, 4479.417675702203, 4482.439695038121, 4490.2142125127975, 2422.49127683185, 2445.474223042019, 2447.9755202447745, 2460.0, 2472.357187131359, 4344.326629372453, 4378.343248512749, 4389.692721509464, 4392.2752745797225, 4417.712848664826, 4698.671093023524, 4726.929096063562, 4732.81679580105, 4735.644826638016, 4759.381280433521, 4123.9381409278885, 4345.391645169679, 4365.541618130114, 4375.693701440996, 4420.9336859947125, 4552.0, 4571.657125715518, 4593.047954619206, 4601.838935637236, 4629.4676112247525, 4494.763939916549, 4518.254488009442, 4532.0, 4540.250858608292, 4551.431071116111, 4080.6123418275215, 4346.760928148982, 4352.477702675699, 4372.191144638252, 4374.103308821747, 3463.1688394785574, 3463.4977928200774, 3463.9826800865835, 3480.3909315869114, 3482.7014170647253, 4594.494605593315, 4600.3329517219145, 4607.7191790064135, 4612.354270402214, 5053.216751403538, 4915.11527924977, 5137.614678899023, 5139.1263485206855, 5140.6096165283325, 5150.716710580954, 5080.593552515716, 5134.5635620972635, 5140.892234183967, 5181.1969144782615, 5181.896362072813, 4865.416150061874, 5032.3206367139965, 5065.848024559269, 5076.314697515788, 5084.618653600921, 5135.897282054413, 5151.459096794782, 5157.716325602128, 5164.32863727728, 5165.483451654804, 5047.545720885138, 5050.393952725696, 5066.72133032672, 5088.949110508847, 5097.980403919305], "value": 3756.3152894464847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3706659740882, 579.7912751409494, 651.5797310734575, 702.483674499243, 745.5452174173753, 12862.935685321572, 13307.93346033271, 13363.933180334108, 13392.263425511594, 13395.933020334896, 11591.724741273882, 12726.300053497078, 12900.290484023375, 12920.418581163847, 12950.417231224594, 612.5620181570176, 736.7384578474645, 749.3031480722939, 759.5784339691496, 769.0579040675157, 767.9193684663114, 769.3076231391736, 771.8147644565292, 774.2605811450052, 774.7598244544209, 645.132297060453, 831.7255305749088, 833.8415701016818, 834.1950018232404, 834.2200042959852, 803.935685145187, 817.8241678039191, 819.2872201184948, 819.6967122164777, 820.4461988158029, 597.7758340622266, 598.5839841310291, 598.8951933411636, 599.9520038396918, 600.3516202501331, 12638.74722505554, 12675.619731408073, 12690.619281421572, 12706.936465317614, 12715.0, 12182.51269949201, 12288.385580720927, 12551.811722824164, 12601.30692811895, 12844.807327890088, 11646.708832279177, 11791.292522448595, 11863.762724745544, 11897.821532677017, 12163.2702037878, 4115.300398932189, 4118.876433706993, 4182.184474027566, 4234.745915245065, 4241.1941731071, 59634.10548841771, 60010.69994650043, 61211.38788612146, 61436.0, 61520.384796152364, 10182.847257291147, 10233.079022887976, 10244.282900197064, 10245.129164020993, 10362.430066346424, 18439.446816595257, 18489.5377615558, 18552.81447185511, 18558.44324670262, 18572.0, 6828.317168283232, 6887.311268873073, 6919.792406227822, 6939.028536004963, 7032.824179395454, 3694.0, 3713.6843368313457, 3750.062484378905, 3776.0371105368504, 3797.6771974382486, 2790.079273839628, 2901.5937768712397, 2902.4775540402507, 2904.549794781812, 2905.7675385969073, 477.0316257996273, 484.36306257271576, 485.75469387958856, 487.4418790484875, 487.94388645305537, 433.5036383341057, 523.1159340714181, 524.7009204753331, 528.9814856480082, 543.4321134414546, 7173.569585824867, 7319.963400182965, 7336.559806411632, 7441.330280274801, 7865.646045927947, 471.9764011799396, 496.4142312071718, 509.93115929349807, 518.8547206782107, 531.4021725558746, 3373.6963673269524, 3506.8246587670515, 3625.3111908737255, 3650.3429382711365, 3703.0, 51707.48292517102, 52281.0, 60595.697021514614, 83339.5833020831, 87918.0, 82825.58587207146, 83016.58491707624, 83418.58290708507, 83726.0, 83916.58041709752, 43696.0, 45661.771691142, 72411.0, 72982.2701772986, 74316.25683743201, 6928.653567321715, 6988.252936765809, 7028.191757947901, 7101.467389945671, 7201.207867134715, 43753.0, 43924.780376097915, 45428.54571454243, 45663.771681141385, 46047.76976115098, 57060.71469642625, 62943.6852815733, 65312.346876530624, 65943.67028164829, 66064.6696766513, 9320.300977426585, 9620.037996200324, 9679.177269932135, 11207.71980700489, 11528.481218345027, 13324.866751332362, 13460.25968571738, 13541.72916541644, 13895.444182232597, 14143.858561414254, 12363.258204507758, 12827.358632068546, 12860.356982151043, 12976.416061277454, 13165.670858228621, 11883.287002779862, 11999.40002999829, 12156.331401772633, 12660.0, 13289.468421263042, 11255.718607034889, 11351.0, 11363.318200907972, 11594.304341739522, 11818.113641476752, 9927.305088643725, 10476.319039262424, 10501.632442864464, 10786.946065269623, 10895.564177432814, 9764.9023509764, 9880.341197387152, 9887.69885099955, 9916.157126644315, 9939.801203976025], "value": 6391.4122600501505, "unit": "score"}], "name": "dromaeo_css", "value": 6417.475766356978, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 112.0, 113.0, 113.0, 111.0, 113.0, 111.0, 113.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 124.0, 176.0, 123.0, 124.0, 146.0, 123.0, 172.0, 123.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 160.0, 162.0, 162.0, 161.0, 162.0, 160.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 135.0, 77.0, 76.0, 75.0, 75.0, 98.0, 76.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 119.0, 85.0, 86.0, 86.0, 115.0, 84.0, 85.0, 129.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 64.0, 66.0, 64.0, 66.0, 63.0, 63.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 78.0, 88.0, 80.0, 80.0, 78.0, 78.0, 80.0, 78.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 127.0, 127.0, 128.0, 129.0, 131.0, 131.0, 177.0, 132.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 170.0, 166.0, 169.0, 168.0, 170.0, 170.0, 169.0, 166.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 70.0, 67.0, 66.0, 65.0, 66.0, 67.0, 67.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1528.6, "alertThreshold": 2.0}]} 16:52:23 INFO - Return code: 0 16:52:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:23 INFO - # TBPL SUCCESS # 16:52:23 INFO - Running post-action listener: _resource_record_post_action 16:52:23 INFO - ##### 16:52:23 INFO - ##### Finished run-tests step (success) 16:52:23 INFO - ##### 16:52:23 INFO - Running post-run listener: _resource_record_post_run 16:52:24 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 534650880; Read time: 0; Write time: 444272 16:52:24 INFO - TinderboxPrint: CPU usage
13.0% 16:52:24 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:52:24 INFO - TinderboxPrint: I/O write bytes / time
534,650,880 / 444,272 16:52:24 INFO - TinderboxPrint: CPU idle
8,328.5 (86.9%) 16:52:24 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 16:52:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:52:24 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 245506048; Read time: 0; Write time: 242840 16:52:24 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288858112; Read time: 0; Write time: 201412 16:52:24 INFO - Running post-run listener: _upload_blobber_files 16:52:24 INFO - Blob upload gear active. 16:52:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:24 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 16:52:24 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:52:24 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:52:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:26 INFO - (blobuploader) - INFO - Done attempting. 16:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:26 INFO - (blobuploader) - INFO - Done attempting. 16:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:30 INFO - (blobuploader) - INFO - Done attempting. 16:52:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:30 INFO - Return code: 0 16:52:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/838b22cbfdab0ad90c228ba4a2ae5d24d5646dda519f978208c17a21ee02e7975e9f7a1ec5e340b06180f63e6071cc6225e3f73476332acc507f239fd44c4989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b24f82952616dd469644337f6e4f371c6ae9f06bd633800613d3976bc01c44310e59479330ff33add11af8c391f8fd96288c60fd3d54a02d8f1de496f1b60b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca566996655fbbf2d238202d2ddf70724b53de289ac404567af54deebff5f8e75ee4ef9725ae14bfe545caa74b0d07f26fcf1110cfb89761fb9829c07e6a970d"} 16:52:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:30 INFO - Contents: 16:52:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/838b22cbfdab0ad90c228ba4a2ae5d24d5646dda519f978208c17a21ee02e7975e9f7a1ec5e340b06180f63e6071cc6225e3f73476332acc507f239fd44c4989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b24f82952616dd469644337f6e4f371c6ae9f06bd633800613d3976bc01c44310e59479330ff33add11af8c391f8fd96288c60fd3d54a02d8f1de496f1b60b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca566996655fbbf2d238202d2ddf70724b53de289ac404567af54deebff5f8e75ee4ef9725ae14bfe545caa74b0d07f26fcf1110cfb89761fb9829c07e6a970d"} 16:52:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:30 INFO - Copying logs to upload dir... 16:52:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.711697 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-05 16:52:30.002976) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 16:52:30.006465) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/838b22cbfdab0ad90c228ba4a2ae5d24d5646dda519f978208c17a21ee02e7975e9f7a1ec5e340b06180f63e6071cc6225e3f73476332acc507f239fd44c4989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b24f82952616dd469644337f6e4f371c6ae9f06bd633800613d3976bc01c44310e59479330ff33add11af8c391f8fd96288c60fd3d54a02d8f1de496f1b60b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca566996655fbbf2d238202d2ddf70724b53de289ac404567af54deebff5f8e75ee4ef9725ae14bfe545caa74b0d07f26fcf1110cfb89761fb9829c07e6a970d"} build_url:https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/JYGhAeQDRKWgxPxErh4PoA/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/Firefox-Non-PGO/sha512/838b22cbfdab0ad90c228ba4a2ae5d24d5646dda519f978208c17a21ee02e7975e9f7a1ec5e340b06180f63e6071cc6225e3f73476332acc507f239fd44c4989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b24f82952616dd469644337f6e4f371c6ae9f06bd633800613d3976bc01c44310e59479330ff33add11af8c391f8fd96288c60fd3d54a02d8f1de496f1b60b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca566996655fbbf2d238202d2ddf70724b53de289ac404567af54deebff5f8e75ee4ef9725ae14bfe545caa74b0d07f26fcf1110cfb89761fb9829c07e6a970d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 16:52:30.035529) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:52:30.035910) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1467758384.5110-570285398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:52:30.813300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 16:52:30.813606) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 16:52:30.814137) ========= ========= Total master_lag: 1.32 =========