builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-057 starttime: 1468429172.99 results: success (0) buildid: 20160713082444 builduid: 00ba071b47334f308d67368754829ee7 revision: 151aaa2db94d3fabe8958b61904371b8f415065d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:32.987671) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:32.988218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:32.988511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.005973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.006422) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 09:59:33-- 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% 50.1M=0s 2016-07-13 09:59:33 (50.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112231 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.133149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.133441) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017506 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.216443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:33.216744) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 151aaa2db94d3fabe8958b61904371b8f415065d --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 151aaa2db94d3fabe8958b61904371b8f415065d --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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 09:59:33,984 truncating revision to first 12 chars 2016-07-13 09:59:33,984 Setting DEBUG logging. 2016-07-13 09:59:33,984 attempt 1/10 2016-07-13 09:59:33,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/151aaa2db94d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 09:59:34,314 unpacking tar archive at: mozilla-central-151aaa2db94d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.772558 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:34.006099) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:34.006695) ========= script_repo_revision: 151aaa2db94d3fabe8958b61904371b8f415065d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:34.007520) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:34.008105) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:59:34.025749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-13 09:59:34.026245) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:59:34 INFO - MultiFileLogger online at 20160713 09:59:34 in /builds/slave/test 09:59:34 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 09:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:59:34 INFO - {'append_to_log': False, 09:59:34 INFO - 'base_work_dir': '/builds/slave/test', 09:59:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:59:34 INFO - 'branch': 'Firefox-Non-PGO', 09:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:34 INFO - 'config_files': ('talos/linux_config.py',), 09:59:34 INFO - 'default_actions': ('clobber', 09:59:34 INFO - 'read-buildbot-config', 09:59:34 INFO - 'download-and-extract', 09:59:34 INFO - 'populate-webroot', 09:59:34 INFO - 'create-virtualenv', 09:59:34 INFO - 'install', 09:59:34 INFO - 'run-tests'), 09:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:34 INFO - 'download_minidump_stackwalk': True, 09:59:34 INFO - 'download_symbols': 'ondemand', 09:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:34 INFO - 'installer_path': 'installer.exe', 09:59:34 INFO - 'log_level': 'info', 09:59:34 INFO - 'log_name': 'talos', 09:59:34 INFO - 'log_to_console': True, 09:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:59:34 INFO - 'opt_config_files': (), 09:59:34 INFO - 'pip_index': False, 09:59:34 INFO - 'sps_profile': False, 09:59:34 INFO - 'sps_profile_interval': 0, 09:59:34 INFO - 'suite': 'dromaeojs', 09:59:34 INFO - 'system_bits': '32', 09:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:34 INFO - 'title': 'talos-linux64-ix-057', 09:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:34 INFO - 'use_talos_json': True, 09:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:34 INFO - 'work_dir': 'build'} 09:59:34 INFO - ##### 09:59:34 INFO - ##### Running clobber step. 09:59:34 INFO - ##### 09:59:34 INFO - Running pre-action listener: _resource_record_pre_action 09:59:34 INFO - Running main action method: clobber 09:59:34 INFO - rmtree: /builds/slave/test/build 09:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:59:35 INFO - Running post-action listener: _resource_record_post_action 09:59:35 INFO - ##### 09:59:35 INFO - ##### Finished clobber step (success) 09:59:35 INFO - ##### 09:59:35 INFO - ##### 09:59:35 INFO - ##### Running read-buildbot-config step. 09:59:35 INFO - ##### 09:59:35 INFO - Running pre-action listener: _resource_record_pre_action 09:59:35 INFO - Running main action method: read_buildbot_config 09:59:35 INFO - Using buildbot properties: 09:59:35 INFO - { 09:59:35 INFO - "project": "", 09:59:35 INFO - "product": "firefox", 09:59:35 INFO - "script_repo_revision": "production", 09:59:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:59:35 INFO - "repository": "", 09:59:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:59:35 INFO - "buildid": "20160713082444", 09:59:35 INFO - "pgo_build": "False", 09:59:35 INFO - "basedir": "/builds/slave/test", 09:59:35 INFO - "buildnumber": 649, 09:59:35 INFO - "slavename": "talos-linux64-ix-057", 09:59:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:59:35 INFO - "platform": "ubuntu64_hw", 09:59:35 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:59:35 INFO - "branch": "mozilla-central", 09:59:35 INFO - "repo_path": "mozilla-central", 09:59:35 INFO - "revision": "151aaa2db94d3fabe8958b61904371b8f415065d", 09:59:35 INFO - "stage_platform": "linux64", 09:59:35 INFO - "builduid": "00ba071b47334f308d67368754829ee7", 09:59:35 INFO - "slavebuilddir": "test" 09:59:35 INFO - } 09:59:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:59:35 INFO - Running post-action listener: _resource_record_post_action 09:59:35 INFO - ##### 09:59:35 INFO - ##### Finished read-buildbot-config step (success) 09:59:35 INFO - ##### 09:59:35 INFO - ##### 09:59:35 INFO - ##### Running download-and-extract step. 09:59:35 INFO - ##### 09:59:35 INFO - Running pre-action listener: _resource_record_pre_action 09:59:35 INFO - Running main action method: download_and_extract 09:59:35 INFO - mkdir: /builds/slave/test/build/tests 09:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:35 INFO - https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:59:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:59:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:59:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:36 INFO - Downloaded 1361 bytes. 09:59:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:59:36 INFO - Using the following test package requirements: 09:59:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:59:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:59:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'jsshell-linux-x86_64.zip'], 09:59:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:59:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:59:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:59:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:59:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:59:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:59: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')]} 09:59:36 INFO - https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:59:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:59:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:38 INFO - Downloaded 22750890 bytes. 09:59:38 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'] 09:59:38 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 09:59:39 INFO - Return code: 0 09:59:39 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 09:59: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')]} 09:59:39 INFO - https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:40 INFO - Downloaded 22750890 bytes. 09:59:40 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'] 09:59:40 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 09:59:41 INFO - Return code: 0 09:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:41 INFO - https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:42 INFO - Downloaded 14403317 bytes. 09:59:42 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'] 09:59:42 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 09:59:43 INFO - Return code: 0 09:59:43 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')]} 09:59:43 INFO - https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:59:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:59:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:59:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VagQ7pt7THiXnW_7zSQLRw/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 09:59:44 INFO - Downloaded 57010443 bytes. 09:59:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:59:44 INFO - mkdir: /builds/slave/test/properties 09:59:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:44 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:44 INFO - Contents: 09:59:44 INFO - build_url:https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:59:45 INFO - Running post-action listener: _resource_record_post_action 09:59:45 INFO - Running post-action listener: set_extra_try_arguments 09:59:45 INFO - ##### 09:59:45 INFO - ##### Finished download-and-extract step (success) 09:59:45 INFO - ##### 09:59:45 INFO - ##### 09:59:45 INFO - ##### Running populate-webroot step. 09:59:45 INFO - ##### 09:59:45 INFO - Running pre-action listener: _resource_record_pre_action 09:59:45 INFO - Running main action method: populate_webroot 09:59:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:45 INFO - '--tppagecycles', 09:59:45 INFO - '7'], 09:59:45 INFO - 'tests': ['tsvgm']}}, 09:59:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['damp', 'tps']}, 09:59:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'tests': ['damp', 'tps']}, 09:59:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['dromaeo_dom']}, 09:59:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['basic_compositor_video']}, 09:59:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['a11yr', 09:59:45 INFO - 'ts_paint', 09:59:45 INFO - 'tpaint', 09:59:45 INFO - 'sessionrestore', 09:59:45 INFO - 'sessionrestore_no_auto_restore', 09:59:45 INFO - 'tabpaint']}, 09:59:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['tsvgx', 09:59:45 INFO - 'tsvgr_opacity', 09:59:45 INFO - 'tart', 09:59:45 INFO - 'tscrollx', 09:59:45 INFO - 'cart']}, 09:59:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:45 INFO - 'tsvgr_opacity', 09:59:45 INFO - 'tart', 09:59:45 INFO - 'tscrollx', 09:59:45 INFO - 'cart']}, 09:59:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'talos_options': ['--disable-e10s'], 09:59:45 INFO - 'tests': ['tp5o']}, 09:59:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'tests': ['tp5o']}, 09:59:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'talos_options': ['--disable-e10s', 09:59:45 INFO - '--xperf_path', 09:59:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:45 INFO - 'tests': ['tp5n']}, 09:59:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:45 INFO - 'talos_options': ['--xperf_path', 09:59:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:45 INFO - 'tests': ['tp5n']}}, 09:59:45 INFO - 'talos.zip': {'path': '', 09:59:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:45 INFO - Running post-action listener: _resource_record_post_action 09:59:45 INFO - ##### 09:59:45 INFO - ##### Finished populate-webroot step (success) 09:59:45 INFO - ##### 09:59:45 INFO - ##### 09:59:45 INFO - ##### Running create-virtualenv step. 09:59:45 INFO - ##### 09:59:45 INFO - Running pre-action listener: _resource_record_pre_action 09:59:45 INFO - Running main action method: create_virtualenv 09:59:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:45 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 09:59:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:45 INFO - Using real prefix '/usr' 09:59:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:47 INFO - Installing pip.................done. 09:59:47 INFO - Return code: 0 09:59:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:47 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')]} 09:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:47 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')]} 09:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:47 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:47 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 09:59:47 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 09:59:47 INFO - Using env: {'DISPLAY': ':0', 09:59:47 INFO - 'HOME': '/home/cltbld', 09:59:47 INFO - 'LANG': 'en_US.UTF-8', 09:59:47 INFO - 'LANGUAGE': 'en_US:en', 09:59:47 INFO - 'LOGNAME': 'cltbld', 09:59:47 INFO - 'MAIL': '/var/mail/cltbld', 09:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:47 INFO - 'MOZ_NO_REMOTE': '1', 09:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:47 INFO - 'NO_EM_RESTART': '1', 09:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:47 INFO - 'PWD': '/builds/slave/test', 09:59:47 INFO - 'SHELL': '/bin/bash', 09:59:47 INFO - 'SHLVL': '1', 09:59:47 INFO - 'TERM': 'linux', 09:59:47 INFO - 'TMOUT': '86400', 09:59:47 INFO - 'USER': 'cltbld', 09:59:47 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:47 INFO - '_': '/tools/buildbot/bin/python'} 09:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:48 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 09:59:48 INFO - Cleaning up... 09:59:48 INFO - Return code: 0 09:59:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:48 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:48 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 09:59:48 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 09:59:48 INFO - Using env: {'DISPLAY': ':0', 09:59:48 INFO - 'HOME': '/home/cltbld', 09:59:48 INFO - 'LANG': 'en_US.UTF-8', 09:59:48 INFO - 'LANGUAGE': 'en_US:en', 09:59:48 INFO - 'LOGNAME': 'cltbld', 09:59:48 INFO - 'MAIL': '/var/mail/cltbld', 09:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:48 INFO - 'MOZ_NO_REMOTE': '1', 09:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:48 INFO - 'NO_EM_RESTART': '1', 09:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:48 INFO - 'PWD': '/builds/slave/test', 09:59:48 INFO - 'SHELL': '/bin/bash', 09:59:48 INFO - 'SHLVL': '1', 09:59:48 INFO - 'TERM': 'linux', 09:59:48 INFO - 'TMOUT': '86400', 09:59:48 INFO - 'USER': 'cltbld', 09:59:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:48 INFO - '_': '/tools/buildbot/bin/python'} 09:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:48 INFO - Downloading/unpacking psutil>=3.1.1 09:59:48 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 09:59:48 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 09:59:48 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 09:59:48 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 09:59:48 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 09:59:48 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 09:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:50 INFO - Installing collected packages: psutil 09:59:50 INFO - Running setup.py install for psutil 09:59:50 INFO - building 'psutil._psutil_linux' extension 09:59:50 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 09:59:50 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 09:59:50 INFO - building 'psutil._psutil_posix' extension 09:59:50 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 09:59:50 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 09:59:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:50 INFO - Successfully installed psutil 09:59:50 INFO - Cleaning up... 09:59:50 INFO - Return code: 0 09:59:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:50 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:50 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 09:59:50 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 09:59:50 INFO - Using env: {'DISPLAY': ':0', 09:59:50 INFO - 'HOME': '/home/cltbld', 09:59:50 INFO - 'LANG': 'en_US.UTF-8', 09:59:50 INFO - 'LANGUAGE': 'en_US:en', 09:59:50 INFO - 'LOGNAME': 'cltbld', 09:59:50 INFO - 'MAIL': '/var/mail/cltbld', 09:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:50 INFO - 'MOZ_NO_REMOTE': '1', 09:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:50 INFO - 'NO_EM_RESTART': '1', 09:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:50 INFO - 'PWD': '/builds/slave/test', 09:59:50 INFO - 'SHELL': '/bin/bash', 09:59:50 INFO - 'SHLVL': '1', 09:59:50 INFO - 'TERM': 'linux', 09:59:50 INFO - 'TMOUT': '86400', 09:59:50 INFO - 'USER': 'cltbld', 09:59:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:50 INFO - '_': '/tools/buildbot/bin/python'} 09:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:51 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 09:59:51 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 09:59:51 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 09:59:51 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 09:59:51 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 09:59:51 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 09:59:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:53 INFO - Installing collected packages: mozsystemmonitor 09:59:53 INFO - Running setup.py install for mozsystemmonitor 09:59:53 INFO - Successfully installed mozsystemmonitor 09:59:53 INFO - Cleaning up... 09:59:53 INFO - Return code: 0 09:59:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:53 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:53 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 09:59:53 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 09:59:53 INFO - Using env: {'DISPLAY': ':0', 09:59:53 INFO - 'HOME': '/home/cltbld', 09:59:53 INFO - 'LANG': 'en_US.UTF-8', 09:59:53 INFO - 'LANGUAGE': 'en_US:en', 09:59:53 INFO - 'LOGNAME': 'cltbld', 09:59:53 INFO - 'MAIL': '/var/mail/cltbld', 09:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:53 INFO - 'MOZ_NO_REMOTE': '1', 09:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:53 INFO - 'NO_EM_RESTART': '1', 09:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:53 INFO - 'PWD': '/builds/slave/test', 09:59:53 INFO - 'SHELL': '/bin/bash', 09:59:53 INFO - 'SHLVL': '1', 09:59:53 INFO - 'TERM': 'linux', 09:59:53 INFO - 'TMOUT': '86400', 09:59:53 INFO - 'USER': 'cltbld', 09:59:53 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:53 INFO - '_': '/tools/buildbot/bin/python'} 09:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:53 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 09:59:53 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 09:59:53 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 09:59:53 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 09:59:53 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 09:59:53 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 09:59:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:55 INFO - warning: no files found matching '*.txt' 09:59:55 INFO - no previously-included directories found matching 'build' 09:59:55 INFO - no previously-included directories found matching 'dist' 09:59:55 INFO - no previously-included directories found matching '.git*' 09:59:55 INFO - Installing collected packages: jsonschema, functools32 09:59:55 INFO - Running setup.py install for functools32 09:59:55 INFO - warning: no files found matching '*.txt' 09:59:55 INFO - no previously-included directories found matching 'build' 09:59:55 INFO - no previously-included directories found matching 'dist' 09:59:55 INFO - no previously-included directories found matching '.git*' 09:59:55 INFO - Successfully installed jsonschema functools32 09:59:55 INFO - Cleaning up... 09:59:55 INFO - Return code: 0 09:59:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:55 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:55 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 09:59:55 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 09:59:55 INFO - Using env: {'DISPLAY': ':0', 09:59:55 INFO - 'HOME': '/home/cltbld', 09:59:55 INFO - 'LANG': 'en_US.UTF-8', 09:59:55 INFO - 'LANGUAGE': 'en_US:en', 09:59:55 INFO - 'LOGNAME': 'cltbld', 09:59:55 INFO - 'MAIL': '/var/mail/cltbld', 09:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:55 INFO - 'MOZ_NO_REMOTE': '1', 09:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:55 INFO - 'NO_EM_RESTART': '1', 09:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:55 INFO - 'PWD': '/builds/slave/test', 09:59:55 INFO - 'SHELL': '/bin/bash', 09:59:55 INFO - 'SHLVL': '1', 09:59:55 INFO - 'TERM': 'linux', 09:59:55 INFO - 'TMOUT': '86400', 09:59:55 INFO - 'USER': 'cltbld', 09:59:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:55 INFO - '_': '/tools/buildbot/bin/python'} 09:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:55 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 09:59:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 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 09:59:58 INFO - Downloading docopt-0.6.1.tar.gz 09:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:58 INFO - Running setup.py install for blobuploader 09:59:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:58 INFO - Running setup.py install for requests 09:59:59 INFO - Running setup.py install for docopt 09:59:59 INFO - Successfully installed blobuploader requests docopt 09:59:59 INFO - Cleaning up... 09:59:59 INFO - Return code: 0 09:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:59 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 09:59:59 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 09:59:59 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 09:59:59 INFO - Using env: {'DISPLAY': ':0', 09:59:59 INFO - 'HOME': '/home/cltbld', 09:59:59 INFO - 'LANG': 'en_US.UTF-8', 09:59:59 INFO - 'LANGUAGE': 'en_US:en', 09:59:59 INFO - 'LOGNAME': 'cltbld', 09:59:59 INFO - 'MAIL': '/var/mail/cltbld', 09:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:59 INFO - 'MOZ_NO_REMOTE': '1', 09:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:59 INFO - 'NO_EM_RESTART': '1', 09:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:59 INFO - 'PWD': '/builds/slave/test', 09:59:59 INFO - 'SHELL': '/bin/bash', 09:59:59 INFO - 'SHLVL': '1', 09:59:59 INFO - 'TERM': 'linux', 09:59:59 INFO - 'TMOUT': '86400', 09:59:59 INFO - 'USER': 'cltbld', 09:59:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 09:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:59 INFO - '_': '/tools/buildbot/bin/python'} 09:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:59 INFO - Running setup.py (path:/tmp/pip-DSqLk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:59 INFO - Running setup.py (path:/tmp/pip-5wtU0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:59 INFO - Running setup.py (path:/tmp/pip-21IZhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:59 INFO - Running setup.py (path:/tmp/pip-faQEEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:59 INFO - Running setup.py (path:/tmp/pip-GuzBxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:59 INFO - Running setup.py (path:/tmp/pip-bH3JHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:00 INFO - Running setup.py (path:/tmp/pip-pmbB2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:00 INFO - Running setup.py (path:/tmp/pip-Oa0WVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:00 INFO - Running setup.py (path:/tmp/pip-QWJAVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:00 INFO - Running setup.py (path:/tmp/pip-xGOZJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:00 INFO - Running setup.py (path:/tmp/pip-mRg38U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:00 INFO - Running setup.py (path:/tmp/pip-B49tY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:00 INFO - Running setup.py (path:/tmp/pip-jEGTDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:00 INFO - Running setup.py (path:/tmp/pip-RIXEbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:00 INFO - Running setup.py (path:/tmp/pip-76bgK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:00 INFO - Running setup.py (path:/tmp/pip-scFYSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:00 INFO - Running setup.py (path:/tmp/pip-4OW6yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:00:00 INFO - Running setup.py install for manifestparser 10:00:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:00:00 INFO - Running setup.py install for mozcrash 10:00:01 INFO - Running setup.py install for mozdebug 10:00:01 INFO - Running setup.py install for mozdevice 10:00:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for mozfile 10:00:01 INFO - Running setup.py install for mozhttpd 10:00:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for mozinfo 10:00:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for mozInstall 10:00:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for mozleak 10:00:01 INFO - Running setup.py install for mozlog 10:00:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for moznetwork 10:00:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:00:01 INFO - Running setup.py install for mozprocess 10:00:02 INFO - Running setup.py install for mozprofile 10:00:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:00:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:00:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:00:02 INFO - Running setup.py install for mozrunner 10:00:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:00:02 INFO - Running setup.py install for mozscreenshot 10:00:02 INFO - Running setup.py install for moztest 10:00:02 INFO - Running setup.py install for mozversion 10:00:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:00:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:00:02 INFO - Cleaning up... 10:00:02 INFO - Return code: 0 10:00:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:02 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')]} 10:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:02 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')]} 10:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:02 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 10:00:02 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 10:00:02 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 10:00:02 INFO - Using env: {'DISPLAY': ':0', 10:00:02 INFO - 'HOME': '/home/cltbld', 10:00:02 INFO - 'LANG': 'en_US.UTF-8', 10:00:02 INFO - 'LANGUAGE': 'en_US:en', 10:00:02 INFO - 'LOGNAME': 'cltbld', 10:00:02 INFO - 'MAIL': '/var/mail/cltbld', 10:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:02 INFO - 'MOZ_NO_REMOTE': '1', 10:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:02 INFO - 'NO_EM_RESTART': '1', 10:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:02 INFO - 'PWD': '/builds/slave/test', 10:00:02 INFO - 'SHELL': '/bin/bash', 10:00:02 INFO - 'SHLVL': '1', 10:00:02 INFO - 'TERM': 'linux', 10:00:02 INFO - 'TMOUT': '86400', 10:00:02 INFO - 'USER': 'cltbld', 10:00:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 10:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:02 INFO - '_': '/tools/buildbot/bin/python'} 10:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:00:02 INFO - Running setup.py (path:/tmp/pip-eB4BSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:00:02 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)) 10:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:00:02 INFO - Running setup.py (path:/tmp/pip-B4ivw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:00:02 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)) 10:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:00:02 INFO - Running setup.py (path:/tmp/pip-oHz1yD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:00:03 INFO - Running setup.py (path:/tmp/pip-jWvROO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:00:03 INFO - Running setup.py (path:/tmp/pip-Quyu8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:00:03 INFO - Running setup.py (path:/tmp/pip-E6If0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:03 INFO - Running setup.py (path:/tmp/pip-2mNfFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:03 INFO - Running setup.py (path:/tmp/pip-gHqZZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:03 INFO - Running setup.py (path:/tmp/pip-L7VMgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:03 INFO - Running setup.py (path:/tmp/pip-bdcQ6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:03 INFO - Running setup.py (path:/tmp/pip-BXdUBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:03 INFO - Running setup.py (path:/tmp/pip-66uLQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:03 INFO - Running setup.py (path:/tmp/pip-JYEpfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:03 INFO - Running setup.py (path:/tmp/pip-K8AxOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:03 INFO - Running setup.py (path:/tmp/pip-lZal_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:03 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)) 10:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:03 INFO - Running setup.py (path:/tmp/pip-DlwhoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:04 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)) 10:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:04 INFO - Running setup.py (path:/tmp/pip-UFoDE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:04 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)) 10:00:04 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)) 10:00:04 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)) 10:00:04 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)) 10:00:04 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)) 10:00:04 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)) 10:00:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00:06 INFO - Downloading blessings-1.6.tar.gz 10:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:00:06 INFO - Installing collected packages: blessings 10:00:06 INFO - Running setup.py install for blessings 10:00:06 INFO - Successfully installed blessings 10:00:06 INFO - Cleaning up... 10:00:06 INFO - Return code: 0 10:00:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:06 INFO - Reading from file tmpfile_stdout 10:00:06 INFO - Current package versions: 10:00:06 INFO - argparse == 1.2.1 10:00:06 INFO - blessings == 1.6 10:00:06 INFO - blobuploader == 1.2.4 10:00:06 INFO - docopt == 0.6.1 10:00:06 INFO - functools32 == 3.2.3-2 10:00:06 INFO - jsonschema == 2.5.1 10:00:06 INFO - manifestparser == 1.1 10:00:06 INFO - mozInstall == 1.12 10:00:06 INFO - mozcrash == 0.17 10:00:06 INFO - mozdebug == 0.1 10:00:06 INFO - mozdevice == 0.48 10:00:06 INFO - mozfile == 1.2 10:00:06 INFO - mozhttpd == 0.7 10:00:06 INFO - mozinfo == 0.9 10:00:06 INFO - mozleak == 0.1 10:00:06 INFO - mozlog == 3.2 10:00:06 INFO - moznetwork == 0.27 10:00:06 INFO - mozprocess == 0.23 10:00:06 INFO - mozprofile == 0.28 10:00:06 INFO - mozrunner == 6.12 10:00:06 INFO - mozscreenshot == 0.1 10:00:06 INFO - mozsystemmonitor == 0.3 10:00:06 INFO - moztest == 0.7 10:00:06 INFO - mozversion == 1.4 10:00:06 INFO - psutil == 3.1.1 10:00:06 INFO - requests == 1.2.3 10:00:06 INFO - wsgiref == 0.1.2 10:00:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 10:00:06 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 10:00:06 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 10:00:06 INFO - Using env: {'DISPLAY': ':0', 10:00:06 INFO - 'HOME': '/home/cltbld', 10:00:06 INFO - 'LANG': 'en_US.UTF-8', 10:00:06 INFO - 'LANGUAGE': 'en_US:en', 10:00:06 INFO - 'LOGNAME': 'cltbld', 10:00:06 INFO - 'MAIL': '/var/mail/cltbld', 10:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:06 INFO - 'MOZ_NO_REMOTE': '1', 10:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:06 INFO - 'NO_EM_RESTART': '1', 10:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:06 INFO - 'PWD': '/builds/slave/test', 10:00:06 INFO - 'SHELL': '/bin/bash', 10:00:06 INFO - 'SHLVL': '1', 10:00:06 INFO - 'TERM': 'linux', 10:00:06 INFO - 'TMOUT': '86400', 10:00:06 INFO - 'USER': 'cltbld', 10:00:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 10:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:06 INFO - '_': '/tools/buildbot/bin/python'} 10:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 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)) 10:00:06 INFO - Cleaning up... 10:00:06 INFO - Return code: 0 10:00:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 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 0x1aa6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d67180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', '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 10:00:06 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 10:00:06 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 10:00:06 INFO - Using env: {'DISPLAY': ':0', 10:00:06 INFO - 'HOME': '/home/cltbld', 10:00:06 INFO - 'LANG': 'en_US.UTF-8', 10:00:06 INFO - 'LANGUAGE': 'en_US:en', 10:00:06 INFO - 'LOGNAME': 'cltbld', 10:00:06 INFO - 'MAIL': '/var/mail/cltbld', 10:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:06 INFO - 'MOZ_NO_REMOTE': '1', 10:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:06 INFO - 'NO_EM_RESTART': '1', 10:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:06 INFO - 'PWD': '/builds/slave/test', 10:00:06 INFO - 'SHELL': '/bin/bash', 10:00:06 INFO - 'SHLVL': '1', 10:00:06 INFO - 'TERM': 'linux', 10:00:06 INFO - 'TMOUT': '86400', 10:00:06 INFO - 'USER': 'cltbld', 10:00:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 10:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:06 INFO - '_': '/tools/buildbot/bin/python'} 10:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:00:06 INFO - Cleaning up... 10:00:07 INFO - Return code: 0 10:00:07 INFO - Running post-action listener: _resource_record_post_action 10:00:07 INFO - Running post-action listener: _start_resource_monitoring 10:00:07 INFO - Starting resource monitoring. 10:00:07 INFO - ##### 10:00:07 INFO - ##### Finished create-virtualenv step (success) 10:00:07 INFO - ##### 10:00:07 INFO - ##### 10:00:07 INFO - ##### Running install step. 10:00:07 INFO - ##### 10:00:07 INFO - Running pre-action listener: _resource_record_pre_action 10:00:07 INFO - Running main action method: install 10:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:07 INFO - Reading from file tmpfile_stdout 10:00:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:00:07 INFO - Reading from file tmpfile_stdout 10:00:07 INFO - Output received: 10:00:07 INFO - Usage: mozinstall [options] installer 10:00:07 INFO - Options: 10:00:07 INFO - -h, --help show this help message and exit 10:00:07 INFO - -d DEST, --destination=DEST 10:00:07 INFO - Directory to install application into. [default: 10:00:07 INFO - "/builds/slave/test"] 10:00:07 INFO - --app=APP Application being installed. [default: firefox] 10:00:07 INFO - mkdir: /builds/slave/test/build/application 10:00:07 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'] 10:00:07 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 10:00:24 INFO - Reading from file tmpfile_stdout 10:00:24 INFO - Output received: 10:00:24 INFO - /builds/slave/test/build/application/firefox/firefox 10:00:24 INFO - Running post-action listener: _resource_record_post_action 10:00:24 INFO - ##### 10:00:24 INFO - ##### Finished install step (success) 10:00:24 INFO - ##### 10:00:24 INFO - ##### 10:00:24 INFO - ##### Running run-tests step. 10:00:24 INFO - ##### 10:00:24 INFO - Running pre-action listener: _resource_record_pre_action 10:00:24 INFO - Running main action method: run_tests 10:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:24 INFO - Python 2.7.3 10:00:24 INFO - Return code: 0 10:00:24 INFO - grabbing minidump binary from tooltool 10:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:24 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 0x1d653b0>, '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 0x1d662b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d66740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:24 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 10:00:24 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 10:00:24 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 10:00:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:24 INFO - Return code: 0 10:00:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:00:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:00:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:24 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/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 10:00:24 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/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 10:00:24 INFO - Using env: {'DISPLAY': ':0', 10:00:24 INFO - 'HOME': '/home/cltbld', 10:00:24 INFO - 'LANG': 'en_US.UTF-8', 10:00:24 INFO - 'LANGUAGE': 'en_US:en', 10:00:24 INFO - 'LOGNAME': 'cltbld', 10:00:24 INFO - 'MAIL': '/var/mail/cltbld', 10:00:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:24 INFO - 'MOZ_NO_REMOTE': '1', 10:00:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:24 INFO - 'NO_EM_RESTART': '1', 10:00:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:24 INFO - 'PWD': '/builds/slave/test', 10:00:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:24 INFO - 'SHELL': '/bin/bash', 10:00:24 INFO - 'SHLVL': '1', 10:00:24 INFO - 'TERM': 'linux', 10:00:24 INFO - 'TMOUT': '86400', 10:00:24 INFO - 'USER': 'cltbld', 10:00:24 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583', 10:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:24 INFO - '_': '/tools/buildbot/bin/python'} 10:00:24 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/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 10:00:24 INFO - mozversion application_buildid: 20160713082444 10:00:24 INFO - mozversion application_changeset: 151aaa2db94d3fabe8958b61904371b8f415065d 10:00:24 INFO - mozversion application_display_name: Nightly 10:00:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:24 INFO - mozversion application_name: Firefox 10:00:24 INFO - mozversion application_remotingname: firefox 10:00:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:00:24 INFO - mozversion application_vendor: Mozilla 10:00:24 INFO - mozversion application_version: 50.0a1 10:00:24 INFO - mozversion platform_buildid: 20160713082444 10:00:24 INFO - mozversion platform_changeset: 151aaa2db94d3fabe8958b61904371b8f415065d 10:00:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:00:24 INFO - mozversion platform_version: 50.0a1 10:00:24 INFO - using testdate: 1468429224 10:00:24 INFO - actual date: 1468429224 10:00:24 INFO - starting webserver on 'localhost:58634' 10:00:24 INFO - SUITE-START | Running 2 tests 10:00:24 INFO - TEST-START | dromaeo_css 10:00:24 INFO - operating with platform_type : linux_ 10:00:24 INFO - Initialising browser for dromaeo_css test... 10:00:24 INFO - TEST-INFO | started process 13845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_WaEK/profile http://localhost:58634/getInfo.html) 10:00:28 INFO - PROCESS | 13845 | __metrics Screen width/height:1600/1200 10:00:28 INFO - PROCESS | 13845 | colorDepth:24 10:00:28 INFO - PROCESS | 13845 | Browser inner width/height: 1024/697 10:00:28 INFO - PROCESS | 13845 | __metrics 10:00:29 INFO - PROCESS | 13845 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:00:29 INFO - PROCESS | 13845 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:00:30 INFO - PROCESS | 13845 | console.error: PushService: 10:00:30 INFO - PROCESS | 13845 | stateChangeProcessEnqueue: Error transitioning state 10:00:30 INFO - PROCESS | 13845 | UnknownError 10:00:30 INFO - TEST-INFO | 13845: exit 0 10:00:30 INFO - Browser initialized. 10:00:30 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:30 INFO - TEST-INFO | started process 13930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_WaEK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:42 INFO - PROCESS | 13930 | RSS: Main: 158900224 10:00:42 INFO - PROCESS | 13930 | 10:01:34 INFO - PROCESS | 13930 | 1468429294502 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] 10:03:46 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58634/tests/dromaeo/cssquery-ext.html) 10:03:47 INFO - PROCESS | 13930 | RSS: Main: 271826944 10:03:47 INFO - PROCESS | 13930 | 10:06:50 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-ext.html (next: http://localhost:58634/tests/dromaeo/cssquery-jquery.html) 10:06:50 INFO - PROCESS | 13930 | RSS: Main: 239521792 10:06:50 INFO - PROCESS | 13930 | 10:09:53 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58634/tests/dromaeo/cssquery-mootools.html) 10:09:53 INFO - PROCESS | 13930 | RSS: Main: 237600768 10:09:53 INFO - PROCESS | 13930 | 10:12:55 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58634/tests/dromaeo/cssquery-prototype.html) 10:12:56 INFO - PROCESS | 13930 | RSS: Main: 237084672 10:12:56 INFO - PROCESS | 13930 | 10:15:59 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58634/tests/dromaeo/cssquery-yui.html) 10:16:00 INFO - PROCESS | 13930 | RSS: Main: 217915392 10:16:00 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | Cycle 1(1): loaded http://localhost:58634/tests/dromaeo/cssquery-yui.html (next: http://localhost:58634/tests/dromaeo/cssquery-dojo.html) 10:19:03 INFO - PROCESS | 13930 | RSS: Main: 278740992 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | __start_tp_report 10:19:03 INFO - PROCESS | 13930 | _x_x_mozilla_page_load 10:19:03 INFO - PROCESS | 13930 | _x_x_mozilla_page_load_details 10:19:03 INFO - PROCESS | 13930 | |i|pagename|runs| 10:19:03 INFO - PROCESS | 13930 | |0;dojo.html;2591.1190195333584;2597.1429428288643;2636.314558214864;2641.128427618886;2643.048502539086;4542.886427839306;4614.538546145383;4617.792199351029;4622;4631.212693842046;4544.636429085674;4599.494055653875;4601.815927362902;4627.676062675614;4637.953620463794;4199.727017743852;4206.053637931456;4236.915261694764;4251.3622956556455;4261.659067274611;4238.766867822269;4306;4324.394584758136;4331.783410829462;4336.089421221532;4270.594293542109;4309.396684464177;4318.071614602844;4325.329573916048;4351.390805287262;4174.77038762868;4197.433346498245;4221.598948099926;4224.746515209066;4270.893227669302;4223.535411104776;4248.893777655552;4279.208346455885;4287.6998610097;4321.984333681582;4661.627069834405;4670.976645116787;4718.3158442025715;4725.9527404726205;4729.810807567693;4792.257200133985;4900.901981960376;4953.182724850395;4960.379952505937;5009.248612708123;4998.425181104147;4998.4751601081725;5008.24876268559;5010.799568017275;5023.472535383805;4593.931091033637;4688.249880019181;4699.647526435531;4706.623470122382;4707.364505791743;5007.674501157413;5064.4935506449065;5066.189409694432;5079.4412614612365;5139.614528910347;4869.6591238612955;4898.314236006961;4916.311716359713;4917.041176970456;4919.188333924933;4834.95165048352;4887.462379138292;4949.90100197994;5012.573931215815;5020.748962551931;4873.147199240118;4879.902401951941;4886.755662216875;4907.950920490749;4940.90118197634;4868.561829435297;4870.829520966749;4873.098476781806;4879.365682461328;4910.140725373046;4080.7347522410946;4121.8969525761495;4156.729812562174;4166.541680415152;4207.03238255203;3681.2821499807815;3695.667389934865;3696.778193308356;3733.607971163043;3775.679067279257;4299.398084268205;4330.783460826946;4335.046289816235;4373.037931655031;4373.1472362888935;4837.685550439211;4878.682885612424;4917.926231106536;4991.450940396553;5039.77321020555;4307.5261721210645;4333;4339.132173565299;4366.912661746747;4379.890502737393;4504.324351347259;4513.526079761644;4515.9774201128785;4522.8643140705835;4567.931481027787;4583.358329833893;4640.95578494836;4663.044075964352;4680.625549955927;4811.3745213122775;4861.732604706775;4889.413270407574;4913.1893237615395;4915.18899381598;4959.652824302264;4674.135284972291;4684.742339171242;4685.273782563571;4688.578027977498;4735.502772208868;4725.480197178239;4853.39332583427;4900.534449227318;4916.237983112624;4928.901421971612;4964.255361695775;4966.478519755518;4978.576820970258;4987.576056035277;5031.270465782514;4633.119707255611;4837.612990960644;4853.4418541868135;4856.368672072608;4898.044881248123;4774.283857421415;4774.498677638937;4819.879503012453;4840.588549973291;4845.491223421631;4739.834105806281;4771.809127634856;4776.331313616027;4818.469968303554;4823.710577365369;4736.786844591933;4801.063792560557;4862.367892173995;4872.731999740048;4907;4672.859814205443;4714.5521175488275;4717.127331443694;4736;4737.265723812815 10:19:03 INFO - PROCESS | 13930 | |1;ext.html;539.2571870283801;584.7368684092158;585.458450932887;585.9531237501001;596.5704692621313;29407.705922940764;42273;43135.352969705404;43202.56797432025;47033.29450058252;43111.784441077754;43633.34549981753;43660.978987416376;43746.12507749851;43764.562354376445;7511.962440187792;7692.153863075086;7696.692132314701;7980.08229053659;8140.185981401872;7728.459007869451;10096.848547271797;10415.323004004715;10424.426656533888;10743.033127018567;10539.31494452862;10608.522616482276;10609.31039482435;10610.946945265263;10620.681379558626;7555.055618047744;10258.17934565233;10445.112165465944;10514.789704205874;10531.578736850517;10105.595776168944;10408.427536485491;10415.739606509822;10418.062374386342;10422.426766527838;39662;40061.59938400608;40089.19821603552;40123;40181.39727904084;3033.196203006205;3139.1681204480833;3355.328934213143;3458.4985177149174;3572.1248294167835;30796.2300942476;30847.228819279473;31113.22216944572;31410.685893141006;32329.51505727416;3415.334009868077;3501.9824900875333;3546.131197856542;3589.5513060867393;3622.203115314653;268425.5523390973;269230;270044;270313;270320;33321.16697082544;34271.657283426845;34378.65621343755;55974.72012639911;56231;34462.82768586141;34752.47871281933;34812.477812832825;34962.65037349594;35027.649723502946;14172.149671019772;14495.20276384809;14508.564743057725;14510.564683059525;14515.564533064025;10081.344712593658;10241.129503992095;10362.171026317876;10411.375317480975;10551.525181366856;8027.317677997436;8066.072401673765;8085.231902969209;8140.308073813674;8149.511029338259;3084.599002129709;3091.7062879026457;3128.4212420702243;3135.0751528299206;3156.71589556941;836.7698882807276;844.8479273730787;906.8685040669066;951.7002144324516;975.8633791269228;29020.564691529646;29062.41875162485;29197.416051678847;29567.40865182684;29627.407451850842;986.7187851462298;1015.4059874973224;1019.7960407918446;1035.233926894093;1048.4076496779296;14837;15521.223938803014;15542.844571554366;15629.843701562839;15767.921160394355;404359;409564;410656;410791;412114;50957.49042509602;52243.73878130585;52267.477325227024;52271;52369.738151309;1017.1608423051013;1175.6884425627045;1206.252123683322;1208.5528354508888;1211.6062279759115;26418.207453776417;29677.258068548457;29958;29972.550411743407;30420;42642.78678606587;42976;43071;43100.137997240505;43147.352789707555;288041;292348;294161;295733;296058;22645.660315094956;24957.87521062383;25687.614685779354;26201.868990654926;26229.868850655625;42676;42850.571494285905;43558.346624801285;43693;44518.332225016005;36431.27137457183;36747.63252367549;37287.44068839024;37562.62437375591;37823.81088094542;37230.25539489249;37550.73883745572;37809.432858506596;37934.43098353582;38253.42619860649;36524.26951461009;36743;37783;38278.42582361211;38318.23363532769;32058.198545035626;33522;34196.82901585476;34756.65243347635;34885.82557087198;26167.869160654074;26180.476390472464;26371;26622.733772662024;26649 10:19:03 INFO - PROCESS | 13930 | |2;jquery.html;367.72788136778786;371.71192325947396;375.27571786452154;387.0362796636373;388.19449641992856;16392.508224753263;17347.653046939053;18184.81815181848;18185.7272140918;18191.272349106053;16567.66864662706;17301.82698173018;17327;17681.381151659694;17794;4615.399998000264;4774.427068751737;4806.4472585652575;4810.639202059842;4814.783334749927;3503.1242189452637;3670.724695647824;4693.272542755878;5731.455511726379;6025.698715064251;6034.306054803688;6050.2739671239515;6055.606385584945;6069.544784141207;6088.939110608903;5508.614396992192;5541.476094074142;5541.750621222014;5545.611807173499;5567.49892509674;3383.9830800846084;4978.153713868651;5295.443978382292;5304.310439642822;5351.143816989302;12644.304563249018;12645;12682.239065655998;14259;20977;1934.980650193494;1950.9414717558495;1955.1690531524046;1994.9002549872448;2034.1456588232977;9594.42433453995;9603.855942160873;10868.45657717111;11493.942530287379;13644.386002629906;1710.5638062294036;1753.4126067767313;1771.5393997560598;1777.5378401615544;1793.8744287899851;64286.3571364285;72032;72927.27072729258;73671.26328736698;74419.62790186069;10118.848217276747;10125.696229113139;10145.94927025375;10297.330673506198;10325.173986081096;13952.16287022782;14044.648883777783;14085.788713169311;14088.084274522123;14089.929550352183;7238.565686058853;7370.5946172961;7376.151742549569;7381.889271660822;7433.628318584049;6737.258901520829;6864.828379290558;6887.690053947585;7039.120109986252;7066.364027237573;4309.375140604595;4329.891752706208;4343.869683909487;4363;4459.710118842265;1659.510444418875;1744;1749.2740512687092;1792.4712209898112;1832.7709036370454;750.0436942897849;844.1980118886959;879.2043200903192;916.6425094213262;921.6451666108596;16170.838291616934;16694.332226711038;17460.388886388915;17783.46649600514;17869;863.0592654007162;894.2979760887672;895.6999405199201;900.4462255712821;912;9332.90667093334;9785.510724463891;9789.608415663437;9793.412395256308;9870.605175792887;373087;374094;381064;394814;395811;81189;81271.59364203141;81549.18450815415;81822;82716.58641706753;889.1375365895071;903.9005709371963;910.39003867409;921.4195057114109;928.4661319741176;15439.768403473732;15567.532974010797;15590.22048897528;15664.843351566795;15853.286602102702;38521.61478385292;38971.415428769156;39257.41113883237;40479.392809107296;41024.79487602543;51859;53684.19473707976;55280.44719552753;55409.44590554043;55409.72295138498;7327.959035394174;7390.928315394345;7418.1839997599145;7657.195994420728;7707.190744971922;10258.384496930208;10919.181061420584;10923.289986150874;11172.162087843282;11184.328940263285;7344.7003689301455;7829.530228186327;9675.758106047406;9757.512124393894;9946.900530994597;9693.41839489633;9778.217742580435;9867.654632087842;10022.749431264278;10132.392056476636;6822.806008948513;9746.658866939622;10007.949960250153;10091;10429.530671119666;5882.088276317178;6081.483073938764;6313.842153946188;6460.580062295936;7428.1086269647985;8534.658613655633;9056.773580660536;9204.539773011456;9421.104995025462;9640.903590964 10:19:03 INFO - PROCESS | 13930 | |3;mootools.html;1352.0738294268426;1390.1381143690912;1435.3110506956662;1651.8430749078834;1676.0446545469072;6981.68582413792;7366.8526629467515;7418.851622967537;7433.293837085468;7443.962780186098;6240.531960102988;6366.872662546746;6368.267649220341;6573.769918052869;7306.96346518268;1878.6618408686397;2063.2778527515325;2093.225506562574;2114.027547328231;2116.5872654832315;2067.9896600516977;2072.202202152167;2078.542720601465;2080.5422806982438;2082.2816128435666;1856.3595559531939;2118.248021952208;2165.4694599823065;2169.0889826272924;2171.359448962552;1829.0945981739058;1845.7600511933497;1851.7407562941266;2058.5574101568236;2129.9254526091668;2124.66005439129;2171.5005548723884;2171.6308227601344;2172.92394766184;2173.5435558532727;7103.431725461951;7178.138623365229;7181.8922716159295;7184.856302873965;7189.496735228536;4287.3140297552245;4449.043455657049;4455.46534415869;4456.398386217851;4458.08609235106;2779.3190668286197;2831.164806382122;2866.7419932205994;2871;2885.2498350428828;826.446280991737;838.1869586501084;858.8625819868854;862.1895418306772;864.8616221404608;9526.523673816351;9704.02959704024;9707.174890134347;9762.219022478255;9767.658131965347;3891.883243502643;4001.679865610744;4077.898052548651;4128.649064829463;4138.234426631083;8427.41008129425;8439.451435656663;8443.155684431507;8448;8461.23848853606;4372.256716358227;4375.956240437618;4375.978120109379;4380.824767009348;4381.474223093249;3911.7482405629926;4050.1697152084344;4059.9594004060173;4067.8576249831112;4068.2677118119022;3379.6620337966497;3426.7258619310396;3501.3347463981763;3503.737219708481;3507.6667716566885;1127.182792475465;1142.3431526872082;1148.2306854407566;1162.4885050577718;1168.8480497535265;817.7710241132493;825.005867929144;840.5293035899915;852.6717213872704;857.3698331726176;4692.366530518405;4722.95277047232;4880.926786098212;4949.925751113737;4981.078500477351;409.60063937661135;426.2561829607355;430.36521131331034;903.2833160005995;939.196986576481;2747.9450410991667;3360.7143392811395;3536.717062634983;3655.3420384331075;3708.8145592720257;8566.957165214259;8659.393842431095;8704.086070962458;8708.825823483621;8728.127187281221;7832.686692532349;8357.414980951397;8721.869171962426;8737.082606326372;8819.250363719155;6542;6622.536422450478;6655.301193374626;6664.400203981665;6685;4909.042736666316;4974.9751251243515;5074.340335756328;5111.539961403491;5133;7701.422393320411;7923.128455869965;7923.960380198062;7931.405144614062;8020.197980201933;7981.361491080584;8093.55485448306;8107.067687216046;8145.429819912786;8156;6245.344238855037;6276.152719383008;6284.020533531922;6337.397947195009;6358.936410635834;6816.2842901496615;6900.482463815133;6905.447564194752;6909.343612356818;6913.75801846933;6233.758505088168;6339.175907132043;6384.329645387354;6418.101465794886;6450.451711604566;6160.476359509492;6199.287081985449;6199.318075011655;6304.085907543472;6323.146375239284;5724.885502289848;5727.914081288701;5777.075667893117;5778.595498315078;5783.7397317119985;3459.308138372285;4318.740875547477;4328.653707703439;4330.090680957034;4335.501417337046;4346.717463364871;4363;4365;4375.343698445258;4470.754108524063 10:19:03 INFO - PROCESS | 13930 | |4;prototype.html;298.39277071160194;303.4886216724819;304.3927364907011;306.080228912119;307.6200891898505;4862.319275301456;4875.951240487594;4892.266160075986;4909.386326709161;4964.627652926027;4794.9280760788615;4797.448293446255;4874.463808981018;4881.682690625115;4883.829065982692;3574.8391322390426;3677.705783537324;3729.272791805599;3818.331791936414;3825.718384341235;3764.682361173581;3792.5259342582176;3805.8962900758747;3806.4290356446477;3828.272628200647;3603.9099022524383;3763.34141525232;3763.927280724994;3768.359378905579;3786.469894214812;3653.4702468142236;3773.3585290500682;3818.8472461101524;3826.2730081284744;3831.7700937943814;3364.226227967581;3369.1745522346937;3373.3590617782543;3374.8481318340487;3393.389189945809;4763.309320148559;4767;4772.947632518016;4778.2115950868065;4781.342273161034;4260.403543503912;4347.478302603676;4364.978175109136;4385.934210986838;4399.824007039714;5055.4186268579215;5059.392872855244;5060.671056381323;5102.336696229503;5116.744162791845;3693.3351996640863;3697.85208591656;3711.4247291669835;3716.739828212026;3725.807741522714;5113.565346945476;5238.397584277857;5251.396089449724;5316.893662126736;5318.760655770499;3728.8694895678523;3748.7066961898104;3759.8496060157836;3765.3787125124595;3769.1707824278615;3794.6015668354976;3805.9048523786573;3837.175007373428;3856.4162011452395;3856.6721828644318;4337.37108119321;4360.912781744348;4362.71642343253;4365.2797288447055;4371.737695738266;4854.956184420366;5035.144025515671;5037.899242015139;5044.646874718735;5051.87370315745;1300.2133709106045;1310.0763961407185;1321.914075585084;1321.9537316193887;1322.9019913471795;842.5424016451573;859.8864470510704;914.3005600715458;945.6312038305067;945.6595625574926;4428.955710442854;4499.955000449953;4505.144022635689;4508.233600287959;4513.954860451419;5106.234064890296;5149.304843846033;5177.896442071137;5207.766194974027;5212.166053431432;4249.511306199826;4378.846740364072;4440.800163992628;4459.866204013885;4512.977435112869;2758.4896794093156;2763.2677340504783;2768.473989941945;2783.554631258989;2789.4978903797105;4938.827141050046;4972.403311602631;4988;4995.400551933792;5003.949960500348;5095.337606111182;5340.599455040888;5348.304720386403;5380.488853558905;5382.19696401702;4978;4991.700497970133;4994.525520075587;5001.599552125411;5006.89986200274;5220.686758794484;5222.3472065991755;5227.712475813867;5229.529342359206;5233.790648374023;4857.902841943071;5137.743112844418;5154.14956532168;5167.664101833519;5197.298364720715;4955.900881982268;4963.677360971526;4966.081274964144;4975.228839529878;4979.850604481871;3664.212194378115;3680.300742858848;3692.981535092415;3698.6116457771545;3712.1647629283193;5155.200943853709;5224.242484839753;5249.527542521038;5272.314599102246;5297.443768404262;5070.923936141035;5074;5099.286099945936;5102.515261050195;5114.2840002400435;5088.872778180427;5109.6678715883345;5125.128728116304;5136.383633963948;5153.7165455898785;4779.617630589614;4931.432885218246;5020.3724534433195;5031.320771695774;5049.444561098349;4978.6763860348965;5122.6926384415565;5137.588992880485;5140.228965655182;5155.845324640267;4990.8253211137435;5020.221865610836;5062.620303477181;5064.873378165576;5076.695398276115 10:19:03 INFO - PROCESS | 13930 | |5;yui.html;699.8845190543562;722.2632914427285;730.5981710059469;741.9538450784395;752.1312883619421;11719.472623731932;12673.556425525108;12690.238585684865;12693.428795704192;12700.809487857667;12080.577179798709;12217.328046957413;12224.816627750568;12227.14409991301;12240.693982650439;609.8140067279467;745.4260219630881;769.8498792735419;771.2326235395789;776.6466257852692;769.3268390158611;772.9188435214306;775.6587101675273;775.8836174573831;777.8872063550782;646.7800947677789;833.2500749325625;834.586879494651;835.3609488741118;835.4152093534519;788.1251810490351;807;808.1877712898558;813.7436707437139;814.1695470619934;603.6317846113867;603.7373742422017;603.7766026570197;604.0606856338392;604.3291945940003;12472.314022728746;12495.562655307112;12509.937450312782;12523.812142817866;12524.060695447877;12737.617871463872;12992;13060.738785224243;13120.540781072617;13466.326683665875;11660.104531344554;11707.94146029273;11809.114316426303;11812.468438920183;12049.518019279218;4052.5542190359038;4085.836566537335;4101.446304748881;4110.958890411088;4111.8560850370095;60959.695201524155;61057.6947115266;61618;62811.68594157046;62822.685886570274;9842.311038227399;10175.135113515435;10206.948965255126;10247.795044099077;10288.639897603547;19074.332398366267;19105.90447047756;19162.904185479263;19199.808001920082;19206.039698015044;5826.737796799154;5849.268841394825;5870.706464676835;5879.52963762898;6022.548308876764;3579.480975258573;3587.515685382492;3661.176235347079;3703.2223233121344;3798.3352913240074;2590.963614554156;2707.2555047362134;2708.9593656095176;2710.471458065698;2711.755941965193;457.9885502862455;483.6324393460995;499.86003918902765;515.1680036740637;577.8006587727227;509.2844553402475;511.882267078574;529.9152135658276;531.1528112660301;592.431265984651;8133.837323253501;8302.8339433211;8387.874181887277;8451.830963380698;8505.53219572917;584.2054805464564;595.1875689683551;595.8152972578428;602.5902386377305;615.6429271022796;4039.2123535910805;4097.221527909687;4154.813033413504;4166.958330416717;4254.680898932531;62038.689806550676;104485;106289;109349;110352;91632.54183729038;92307.53846230726;93638;93641.5317923406;93860;49455.25817112746;57464.71267643635;78393;78806.21193788103;80047.30090460477;8066.959665201636;8087.8382432349845;8180.46826956246;8197.221263979913;8208.179182081984;47061;48666.51333486762;50044.24933626071;50261.98952041877;51462.74268628633;63208.36791632025;71396.28603714104;71661.64169179121;71900.64049679718;73462.26537734768;11162.107031437628;11289.491972861406;11512.13658975597;11619.767604648028;11909.464074116513;11943.82084268754;12042.397880105784;12082.879171208175;12277.631671049883;12397.070219733376;11179.66461006171;11323;11546.653600392001;11680.299182049104;12043.578474753342;11141.220114592224;11344.546218151181;11851.644450666494;11862.881371186179;11862.881371186179;10630.946845265724;10995.450227488753;11103.72240693989;11174.720631984266;11176.776464470828;8806.559672016501;9307.743454633537;9408.905910941077;9421.152096311365;9565.713028609152;9326.626934922528;9692.75768105803;9790.314677972758;9892.151734787858;9905.405675659484 10:19:03 INFO - PROCESS | 13930 | __end_tp_report 10:19:03 INFO - PROCESS | 13930 | __start_cc_report 10:19:03 INFO - PROCESS | 13930 | _x_x_mozilla_cycle_collect,1080 10:19:03 INFO - PROCESS | 13930 | __end_cc_report 10:19:03 INFO - PROCESS | 13930 | __startTimestamp1468430343516__endTimestamp 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | ------- Summary: start ------- 10:19:03 INFO - PROCESS | 13930 | Number of tests: 6 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#0] dojo.html Cycles:165 Average:4584.27 Median:4714.55 stddev:467.42 (9.9%) stddev-sans-first:441.93 10:19:03 INFO - PROCESS | 13930 | Values: 2591.1 2597.1 2636.3 2641.1 2643.0 4542.9 4614.5 4617.8 4622.0 4631.2 4544.6 4599.5 4601.8 4627.7 4638.0 4199.7 4206.1 4236.9 4251.4 4261.7 4238.8 4306.0 4324.4 4331.8 4336.1 4270.6 4309.4 4318.1 4325.3 4351.4 4174.8 4197.4 4221.6 4224.7 4270.9 4223.5 4248.9 4279.2 4287.7 4322.0 4661.6 4671.0 4718.3 4726.0 4729.8 4792.3 4900.9 4953.2 4960.4 5009.2 4998.4 4998.5 5008.2 5010.8 5023.5 4593.9 4688.2 4699.6 4706.6 4707.4 5007.7 5064.5 5066.2 5079.4 5139.6 4869.7 4898.3 4916.3 4917.0 4919.2 4835.0 4887.5 4949.9 5012.6 5020.7 4873.1 4879.9 4886.8 4908.0 4940.9 4868.6 4870.8 4873.1 4879.4 4910.1 4080.7 4121.9 4156.7 4166.5 4207.0 3681.3 3695.7 3696.8 3733.6 3775.7 4299.4 4330.8 4335.0 4373.0 4373.1 4837.7 4878.7 4917.9 4991.5 5039.8 4307.5 4333.0 4339.1 4366.9 4379.9 4504.3 4513.5 4516.0 4522.9 4567.9 4583.4 4641.0 4663.0 4680.6 4811.4 4861.7 4889.4 4913.2 4915.2 4959.7 4674.1 4684.7 4685.3 4688.6 4735.5 4725.5 4853.4 4900.5 4916.2 4928.9 4964.3 4966.5 4978.6 4987.6 5031.3 4633.1 4837.6 4853.4 4856.4 4898.0 4774.3 4774.5 4819.9 4840.6 4845.5 4739.8 4771.8 4776.3 4818.5 4823.7 4736.8 4801.1 4862.4 4872.7 4907.0 4672.9 4714.6 4717.1 4736.0 4737.3 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#1] ext.html Cycles:180 Average:47538.24 Median:34234.24 stddev:86913.38 (253.9%) stddev-sans-first:87085.56 10:19:03 INFO - PROCESS | 13930 | Values: 539.3 584.7 585.5 586.0 596.6 29407.7 42273.0 43135.4 43202.6 47033.3 43111.8 43633.3 43661.0 43746.1 43764.6 7512.0 7692.2 7696.7 7980.1 8140.2 7728.5 10096.8 10415.3 10424.4 10743.0 10539.3 10608.5 10609.3 10610.9 10620.7 7555.1 10258.2 10445.1 10514.8 10531.6 10105.6 10408.4 10415.7 10418.1 10422.4 39662.0 40061.6 40089.2 40123.0 40181.4 3033.2 3139.2 3355.3 3458.5 3572.1 30796.2 30847.2 31113.2 31410.7 32329.5 3415.3 3502.0 3546.1 3589.6 3622.2 268425.6 269230.0 270044.0 270313.0 270320.0 33321.2 34271.7 34378.7 55974.7 56231.0 34462.8 34752.5 34812.5 34962.7 35027.6 14172.1 14495.2 14508.6 14510.6 14515.6 10081.3 10241.1 10362.2 10411.4 10551.5 8027.3 8066.1 8085.2 8140.3 8149.5 3084.6 3091.7 3128.4 3135.1 3156.7 836.8 844.8 906.9 951.7 975.9 29020.6 29062.4 29197.4 29567.4 29627.4 986.7 1015.4 1019.8 1035.2 1048.4 14837.0 15521.2 15542.8 15629.8 15767.9 404359.0 409564.0 410656.0 410791.0 412114.0 50957.5 52243.7 52267.5 52271.0 52369.7 1017.2 1175.7 1206.3 1208.6 1211.6 26418.2 29677.3 29958.0 29972.6 30420.0 42642.8 42976.0 43071.0 43100.1 43147.4 288041.0 292348.0 294161.0 295733.0 296058.0 22645.7 24957.9 25687.6 26201.9 26229.9 42676.0 42850.6 43558.3 43693.0 44518.3 36431.3 36747.6 37287.4 37562.6 37823.8 37230.3 37550.7 37809.4 37934.4 38253.4 36524.3 36743.0 37783.0 38278.4 38318.2 32058.2 33522.0 34196.8 34756.7 34885.8 26167.9 26180.5 26371.0 26622.7 26649.0 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#2] jquery.html Cycles:180 Average:24270.82 Median:4812.71 stddev:63693.66 (1323.4%) stddev-sans-first:63847.05 10:19:03 INFO - PROCESS | 13930 | Values: 367.7 371.7 375.3 387.0 388.2 16392.5 17347.7 18184.8 18185.7 18191.3 16567.7 17301.8 17327.0 17681.4 17794.0 4615.4 4774.4 4806.4 4810.6 4814.8 3503.1 3670.7 4693.3 5731.5 6025.7 6034.3 6050.3 6055.6 6069.5 6088.9 5508.6 5541.5 5541.8 5545.6 5567.5 3384.0 4978.2 5295.4 5304.3 5351.1 12644.3 12645.0 12682.2 14259.0 20977.0 1935.0 1950.9 1955.2 1994.9 2034.1 9594.4 9603.9 10868.5 11493.9 13644.4 1710.6 1753.4 1771.5 1777.5 1793.9 64286.4 72032.0 72927.3 73671.3 74419.6 10118.8 10125.7 10145.9 10297.3 10325.2 13952.2 14044.6 14085.8 14088.1 14089.9 7238.6 7370.6 7376.2 7381.9 7433.6 6737.3 6864.8 6887.7 7039.1 7066.4 4309.4 4329.9 4343.9 4363.0 4459.7 1659.5 1744.0 1749.3 1792.5 1832.8 750.0 844.2 879.2 916.6 921.6 16170.8 16694.3 17460.4 17783.5 17869.0 863.1 894.3 895.7 900.4 912.0 9332.9 9785.5 9789.6 9793.4 9870.6 373087.0 374094.0 381064.0 394814.0 395811.0 81189.0 81271.6 81549.2 81822.0 82716.6 889.1 903.9 910.4 921.4 928.5 15439.8 15567.5 15590.2 15664.8 15853.3 38521.6 38971.4 39257.4 40479.4 41024.8 51859.0 53684.2 55280.4 55409.4 55409.7 7328.0 7390.9 7418.2 7657.2 7707.2 10258.4 10919.2 10923.3 11172.2 11184.3 7344.7 7829.5 9675.8 9757.5 9946.9 9693.4 9778.2 9867.7 10022.7 10132.4 6822.8 9746.7 10007.9 10091.0 10429.5 5882.1 6081.5 6313.8 6460.6 7428.1 8534.7 9056.8 9204.5 9421.1 9640.9 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#3] mootools.html Cycles:180 Average:4746.14 Median:5092.94 stddev:2576.15 (50.6%) stddev-sans-first:2570.75 10:19:03 INFO - PROCESS | 13930 | Values: 1352.1 1390.1 1435.3 1651.8 1676.0 6981.7 7366.9 7418.9 7433.3 7444.0 6240.5 6366.9 6368.3 6573.8 7307.0 1878.7 2063.3 2093.2 2114.0 2116.6 2068.0 2072.2 2078.5 2080.5 2082.3 1856.4 2118.2 2165.5 2169.1 2171.4 1829.1 1845.8 1851.7 2058.6 2129.9 2124.7 2171.5 2171.6 2172.9 2173.5 7103.4 7178.1 7181.9 7184.9 7189.5 4287.3 4449.0 4455.5 4456.4 4458.1 2779.3 2831.2 2866.7 2871.0 2885.2 826.4 838.2 858.9 862.2 864.9 9526.5 9704.0 9707.2 9762.2 9767.7 3891.9 4001.7 4077.9 4128.6 4138.2 8427.4 8439.5 8443.2 8448.0 8461.2 4372.3 4376.0 4376.0 4380.8 4381.5 3911.7 4050.2 4060.0 4067.9 4068.3 3379.7 3426.7 3501.3 3503.7 3507.7 1127.2 1142.3 1148.2 1162.5 1168.8 817.8 825.0 840.5 852.7 857.4 4692.4 4723.0 4880.9 4949.9 4981.1 409.6 426.3 430.4 903.3 939.2 2747.9 3360.7 3536.7 3655.3 3708.8 8567.0 8659.4 8704.1 8708.8 8728.1 7832.7 8357.4 8721.9 8737.1 8819.3 6542.0 6622.5 6655.3 6664.4 6685.0 4909.0 4975.0 5074.3 5111.5 5133.0 7701.4 7923.1 7924.0 7931.4 8020.2 7981.4 8093.6 8107.1 8145.4 8156.0 6245.3 6276.2 6284.0 6337.4 6358.9 6816.3 6900.5 6905.4 6909.3 6913.8 6233.8 6339.2 6384.3 6418.1 6450.5 6160.5 6199.3 6199.3 6304.1 6323.1 5724.9 5727.9 5777.1 5778.6 5783.7 3459.3 4318.7 4328.7 4330.1 4335.5 4346.7 4363.0 4365.0 4375.3 4470.8 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#4] prototype.html Cycles:180 Average:4235.46 Median:4868.39 stddev:1221.41 (25.1%) stddev-sans-first:1188.55 10:19:03 INFO - PROCESS | 13930 | Values: 298.4 303.5 304.4 306.1 307.6 4862.3 4876.0 4892.3 4909.4 4964.6 4794.9 4797.4 4874.5 4881.7 4883.8 3574.8 3677.7 3729.3 3818.3 3825.7 3764.7 3792.5 3805.9 3806.4 3828.3 3603.9 3763.3 3763.9 3768.4 3786.5 3653.5 3773.4 3818.8 3826.3 3831.8 3364.2 3369.2 3373.4 3374.8 3393.4 4763.3 4767.0 4772.9 4778.2 4781.3 4260.4 4347.5 4365.0 4385.9 4399.8 5055.4 5059.4 5060.7 5102.3 5116.7 3693.3 3697.9 3711.4 3716.7 3725.8 5113.6 5238.4 5251.4 5316.9 5318.8 3728.9 3748.7 3759.8 3765.4 3769.2 3794.6 3805.9 3837.2 3856.4 3856.7 4337.4 4360.9 4362.7 4365.3 4371.7 4855.0 5035.1 5037.9 5044.6 5051.9 1300.2 1310.1 1321.9 1322.0 1322.9 842.5 859.9 914.3 945.6 945.7 4429.0 4500.0 4505.1 4508.2 4514.0 5106.2 5149.3 5177.9 5207.8 5212.2 4249.5 4378.8 4440.8 4459.9 4513.0 2758.5 2763.3 2768.5 2783.6 2789.5 4938.8 4972.4 4988.0 4995.4 5003.9 5095.3 5340.6 5348.3 5380.5 5382.2 4978.0 4991.7 4994.5 5001.6 5006.9 5220.7 5222.3 5227.7 5229.5 5233.8 4857.9 5137.7 5154.1 5167.7 5197.3 4955.9 4963.7 4966.1 4975.2 4979.9 3664.2 3680.3 3693.0 3698.6 3712.2 5155.2 5224.2 5249.5 5272.3 5297.4 5070.9 5074.0 5099.3 5102.5 5114.3 5088.9 5109.7 5125.1 5136.4 5153.7 4779.6 4931.4 5020.4 5031.3 5049.4 4978.7 5122.7 5137.6 5140.2 5155.8 4990.8 5020.2 5062.6 5064.9 5076.7 10:19:03 INFO - PROCESS | 13930 | 10:19:03 INFO - PROCESS | 13930 | [#5] yui.html Cycles:180 Average:18148.24 Median:25385.64 stddev:26582.94 (104.7%) stddev-sans-first:26625.22 10:19:03 INFO - PROCESS | 13930 | Values: 699.9 722.3 730.6 742.0 752.1 11719.5 12673.6 12690.2 12693.4 12700.8 12080.6 12217.3 12224.8 12227.1 12240.7 609.8 745.4 769.8 771.2 776.6 769.3 772.9 775.7 775.9 777.9 646.8 833.3 834.6 835.4 835.4 788.1 807.0 808.2 813.7 814.2 603.6 603.7 603.8 604.1 604.3 12472.3 12495.6 12509.9 12523.8 12524.1 12737.6 12992.0 13060.7 13120.5 13466.3 11660.1 11707.9 11809.1 11812.5 12049.5 4052.6 4085.8 4101.4 4111.0 4111.9 60959.7 61057.7 61618.0 62811.7 62822.7 9842.3 10175.1 10206.9 10247.8 10288.6 19074.3 19105.9 19162.9 19199.8 19206.0 5826.7 5849.3 5870.7 5879.5 6022.5 3579.5 3587.5 3661.2 3703.2 3798.3 2591.0 2707.3 2709.0 2710.5 2711.8 458.0 483.6 499.9 515.2 577.8 509.3 511.9 529.9 531.2 592.4 8133.8 8302.8 8387.9 8451.8 8505.5 584.2 595.2 595.8 602.6 615.6 4039.2 4097.2 4154.8 4167.0 4254.7 62038.7 104485.0 106289.0 109349.0 110352.0 91632.5 92307.5 93638.0 93641.5 93860.0 49455.3 57464.7 78393.0 78806.2 80047.3 8067.0 8087.8 8180.5 8197.2 8208.2 47061.0 48666.5 50044.2 50262.0 51462.7 63208.4 71396.3 71661.6 71900.6 73462.3 11162.1 11289.5 11512.1 11619.8 11909.5 11943.8 12042.4 12082.9 12277.6 12397.1 11179.7 11323.0 11546.7 11680.3 12043.6 11141.2 11344.5 11851.6 11862.9 11862.9 10630.9 10995.5 11103.7 11174.7 11176.8 8806.6 9307.7 9408.9 9421.2 9565.7 9326.6 9692.8 9790.3 9892.2 9905.4 10:19:03 INFO - PROCESS | 13930 | -------- Summary: end -------- 10:19:03 INFO - PROCESS | 13930 | 10:19:04 INFO - TEST-INFO | 13930: exit 0 10:19:04 INFO - TEST-OK | dromaeo_css | took 1119910ms 10:19:04 INFO - TEST-START | kraken 10:19:04 INFO - operating with platform_type : linux_ 10:19:04 INFO - Initialising browser for kraken test... 10:19:04 INFO - TEST-INFO | started process 14123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_B72x2/profile http://localhost:58634/getInfo.html) 10:19:08 INFO - PROCESS | 14123 | __metrics Screen width/height:1600/1200 10:19:08 INFO - PROCESS | 14123 | colorDepth:24 10:19:08 INFO - PROCESS | 14123 | Browser inner width/height: 1024/697 10:19:08 INFO - PROCESS | 14123 | __metrics 10:19:09 INFO - PROCESS | 14123 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:19:09 INFO - PROCESS | 14123 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:10 INFO - TEST-INFO | 14123: exit 0 10:19:10 INFO - Browser initialized. 10:19:10 INFO - Running cycle 1/1 for kraken test... 10:19:10 INFO - TEST-INFO | started process 14204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_B72x2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:22 INFO - PROCESS | 14204 | RSS: Main: 158806016 10:19:22 INFO - PROCESS | 14204 | 10:19:25 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58634/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:26 INFO - PROCESS | 14204 | RSS: Main: 216989696 10:19:26 INFO - PROCESS | 14204 | 10:19:28 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58634/tests/kraken/driver.html?testName=audio-dft) 10:19:29 INFO - PROCESS | 14204 | RSS: Main: 204042240 10:19:29 INFO - PROCESS | 14204 | 10:19:32 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58634/tests/kraken/driver.html?testName=audio-fft) 10:19:33 INFO - PROCESS | 14204 | RSS: Main: 270864384 10:19:33 INFO - PROCESS | 14204 | 10:19:35 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58634/tests/kraken/driver.html?testName=audio-oscillator) 10:19:36 INFO - PROCESS | 14204 | RSS: Main: 181948416 10:19:36 INFO - PROCESS | 14204 | 10:19:38 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58634/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:38 INFO - PROCESS | 14204 | RSS: Main: 185933824 10:19:38 INFO - PROCESS | 14204 | 10:19:42 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58634/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:42 INFO - PROCESS | 14204 | RSS: Main: 221736960 10:19:42 INFO - PROCESS | 14204 | 10:19:46 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58634/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:46 INFO - PROCESS | 14204 | RSS: Main: 218558464 10:19:46 INFO - PROCESS | 14204 | 10:19:49 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58634/tests/kraken/driver.html?testName=json-parse-financial) 10:19:50 INFO - PROCESS | 14204 | RSS: Main: 220303360 10:19:50 INFO - PROCESS | 14204 | 10:19:51 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58634/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:52 INFO - PROCESS | 14204 | RSS: Main: 207785984 10:19:52 INFO - PROCESS | 14204 | 10:19:54 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:55 INFO - PROCESS | 14204 | RSS: Main: 212123648 10:19:55 INFO - PROCESS | 14204 | 10:19:57 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:57 INFO - PROCESS | 14204 | RSS: Main: 210546688 10:19:57 INFO - PROCESS | 14204 | 10:20:00 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:20:01 INFO - PROCESS | 14204 | RSS: Main: 224075776 10:20:01 INFO - PROCESS | 14204 | 10:20:04 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:20:04 INFO - PROCESS | 14204 | RSS: Main: 223760384 10:20:04 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | Cycle 1(1): loaded http://localhost:58634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58634/tests/kraken/driver.html?testName=ai-astar) 10:20:06 INFO - PROCESS | 14204 | RSS: Main: 266014720 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | __start_tp_report 10:20:06 INFO - PROCESS | 14204 | _x_x_mozilla_page_load 10:20:06 INFO - PROCESS | 14204 | _x_x_mozilla_page_load_details 10:20:06 INFO - PROCESS | 14204 | |i|pagename|runs| 10:20:06 INFO - PROCESS | 14204 | |0;ai-astar;104;116;116;114;114;115;113;114;114;116 10:20:06 INFO - PROCESS | 14204 | |1;audio-beat-detection;147;123;124;167;123;125;148;124;176;129 10:20:06 INFO - PROCESS | 14204 | |2;audio-dft;163;160;161;161;164;164;164;162;162;163 10:20:06 INFO - PROCESS | 14204 | |3;audio-fft;76;78;78;122;76;77;77;77;102;77 10:20:06 INFO - PROCESS | 14204 | |4;audio-oscillator;85;88;114;85;85;85;121;84;86;137 10:20:06 INFO - PROCESS | 14204 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;117 10:20:06 INFO - PROCESS | 14204 | |6;imaging-darkroom;147;146;146;146;147;147;147;146;147;147 10:20:06 INFO - PROCESS | 14204 | |7;imaging-desaturate;95;95;94;95;95;94;95;95;95;94 10:20:06 INFO - PROCESS | 14204 | |8;json-parse-financial;64;63;63;63;64;63;64;64;63;62 10:20:06 INFO - PROCESS | 14204 | |9;json-stringify-tinderbox;64;64;64;65;65;65;64;64;65;64 10:20:06 INFO - PROCESS | 14204 | |10;stanford-crypto-aes;79;79;78;78;78;79;78;79;77;84 10:20:06 INFO - PROCESS | 14204 | |11;stanford-crypto-ccm;130;130;128;139;127;138;132;130;164;129 10:20:06 INFO - PROCESS | 14204 | |12;stanford-crypto-pbkdf2;170;167;167;173;163;168;168;167;168;163 10:20:06 INFO - PROCESS | 14204 | |13;stanford-crypto-sha256-iterative;68;67;68;67;66;67;66;65;68;66 10:20:06 INFO - PROCESS | 14204 | __end_tp_report 10:20:06 INFO - PROCESS | 14204 | __start_cc_report 10:20:06 INFO - PROCESS | 14204 | _x_x_mozilla_cycle_collect,1926 10:20:06 INFO - PROCESS | 14204 | __end_cc_report 10:20:06 INFO - PROCESS | 14204 | __startTimestamp1468430406773__endTimestamp 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | ------- Summary: start ------- 10:20:06 INFO - PROCESS | 14204 | Number of tests: 14 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.50 stddev:3.53 (3.1%) stddev-sans-first:1.12 10:20:06 INFO - PROCESS | 14204 | Values: 104.0 116.0 116.0 114.0 114.0 115.0 113.0 114.0 114.0 116.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:138.00 stddev:19.87 (14.4%) stddev-sans-first:20.84 10:20:06 INFO - PROCESS | 14204 | Values: 147.0 123.0 124.0 167.0 123.0 125.0 148.0 124.0 176.0 129.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 10:20:06 INFO - PROCESS | 14204 | Values: 163.0 160.0 161.0 161.0 164.0 164.0 164.0 162.0 162.0 163.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#3] audio-fft Cycles:10 Average:84.00 Median:77.00 stddev:15.51 (20.1%) stddev-sans-first:16.17 10:20:06 INFO - PROCESS | 14204 | Values: 76.0 78.0 78.0 122.0 76.0 77.0 77.0 77.0 102.0 77.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:85.00 stddev:19.47 (22.9%) stddev-sans-first:20.16 10:20:06 INFO - PROCESS | 14204 | Values: 85.0 88.0 114.0 85.0 85.0 85.0 121.0 84.0 86.0 137.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:20:06 INFO - PROCESS | 14204 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:20:06 INFO - PROCESS | 14204 | Values: 147.0 146.0 146.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:20:06 INFO - PROCESS | 14204 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 10:20:06 INFO - PROCESS | 14204 | Values: 64.0 63.0 63.0 63.0 64.0 63.0 64.0 64.0 63.0 62.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 10:20:06 INFO - PROCESS | 14204 | Values: 64.0 64.0 64.0 65.0 65.0 65.0 64.0 64.0 65.0 64.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:1.91 (2.4%) stddev-sans-first:2.03 10:20:06 INFO - PROCESS | 14204 | Values: 79.0 79.0 78.0 78.0 78.0 79.0 78.0 79.0 77.0 84.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:131.00 stddev:11.05 (8.4%) stddev-sans-first:11.58 10:20:06 INFO - PROCESS | 14204 | Values: 130.0 130.0 128.0 139.0 127.0 138.0 132.0 130.0 164.0 129.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.00 stddev:2.95 (1.8%) stddev-sans-first:2.98 10:20:06 INFO - PROCESS | 14204 | Values: 170.0 167.0 167.0 173.0 163.0 168.0 168.0 167.0 168.0 163.0 10:20:06 INFO - PROCESS | 14204 | 10:20:06 INFO - PROCESS | 14204 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.03 (1.5%) stddev-sans-first:1.00 10:20:06 INFO - PROCESS | 14204 | Values: 68.0 67.0 68.0 67.0 66.0 67.0 66.0 65.0 68.0 66.0 10:20:06 INFO - PROCESS | 14204 | -------- Summary: end -------- 10:20:06 INFO - PROCESS | 14204 | 10:20:07 INFO - TEST-INFO | 14204: exit 0 10:20:07 INFO - TEST-OK | kraken | took 63514ms 10:20:07 INFO - SUITE-END | took 1183s 10:20:07 INFO - Completed test suite (00:19:43) 10:20:07 INFO - javascript benchmark 10:20:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2591.1190195333584, 2597.1429428288643, 2636.314558214864, 2641.128427618886, 2643.048502539086, 4542.886427839306, 4614.538546145383, 4617.792199351029, 4622.0, 4631.212693842046, 4544.636429085674, 4599.494055653875, 4601.815927362902, 4627.676062675614, 4637.953620463794, 4199.727017743852, 4206.053637931456, 4236.915261694764, 4251.3622956556455, 4261.659067274611, 4238.766867822269, 4306.0, 4324.394584758136, 4331.783410829462, 4336.089421221532, 4270.594293542109, 4309.396684464177, 4318.071614602844, 4325.329573916048, 4351.390805287262, 4174.77038762868, 4197.433346498245, 4221.598948099926, 4224.746515209066, 4270.893227669302, 4223.535411104776, 4248.893777655552, 4279.208346455885, 4287.6998610097, 4321.984333681582, 4661.627069834405, 4670.976645116787, 4718.3158442025715, 4725.9527404726205, 4729.810807567693, 4792.257200133985, 4900.901981960376, 4953.182724850395, 4960.379952505937, 5009.248612708123, 4998.425181104147, 4998.4751601081725, 5008.24876268559, 5010.799568017275, 5023.472535383805, 4593.931091033637, 4688.249880019181, 4699.647526435531, 4706.623470122382, 4707.364505791743, 5007.674501157413, 5064.4935506449065, 5066.189409694432, 5079.4412614612365, 5139.614528910347, 4869.6591238612955, 4898.314236006961, 4916.311716359713, 4917.041176970456, 4919.188333924933, 4834.95165048352, 4887.462379138292, 4949.90100197994, 5012.573931215815, 5020.748962551931, 4873.147199240118, 4879.902401951941, 4886.755662216875, 4907.950920490749, 4940.90118197634, 4868.561829435297, 4870.829520966749, 4873.098476781806, 4879.365682461328, 4910.140725373046, 4080.7347522410946, 4121.8969525761495, 4156.729812562174, 4166.541680415152, 4207.03238255203, 3681.2821499807815, 3695.667389934865, 3696.778193308356, 3733.607971163043, 3775.679067279257, 4299.398084268205, 4330.783460826946, 4335.046289816235, 4373.037931655031, 4373.1472362888935, 4837.685550439211, 4878.682885612424, 4917.926231106536, 4991.450940396553, 5039.77321020555, 4307.5261721210645, 4333.0, 4339.132173565299, 4366.912661746747, 4379.890502737393, 4504.324351347259, 4513.526079761644, 4515.9774201128785, 4522.8643140705835, 4567.931481027787, 4583.358329833893, 4640.95578494836, 4663.044075964352, 4680.625549955927, 4811.3745213122775, 4861.732604706775, 4889.413270407574, 4913.1893237615395, 4915.18899381598, 4959.652824302264, 4674.135284972291, 4684.742339171242, 4685.273782563571, 4688.578027977498, 4735.502772208868, 4725.480197178239, 4853.39332583427, 4900.534449227318, 4916.237983112624, 4928.901421971612, 4964.255361695775, 4966.478519755518, 4978.576820970258, 4987.576056035277, 5031.270465782514, 4633.119707255611, 4837.612990960644, 4853.4418541868135, 4856.368672072608, 4898.044881248123, 4774.283857421415, 4774.498677638937, 4819.879503012453, 4840.588549973291, 4845.491223421631, 4739.834105806281, 4771.809127634856, 4776.331313616027, 4818.469968303554, 4823.710577365369, 4736.786844591933, 4801.063792560557, 4862.367892173995, 4872.731999740048, 4907.0, 4672.859814205443, 4714.5521175488275, 4717.127331443694, 4736.0, 4737.265723812815], "value": 4555.217914626651, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.2571870283801, 584.7368684092158, 585.458450932887, 585.9531237501001, 596.5704692621313, 29407.705922940764, 42273.0, 43135.352969705404, 43202.56797432025, 47033.29450058252, 43111.784441077754, 43633.34549981753, 43660.978987416376, 43746.12507749851, 43764.562354376445, 7511.962440187792, 7692.153863075086, 7696.692132314701, 7980.08229053659, 8140.185981401872, 7728.459007869451, 10096.848547271797, 10415.323004004715, 10424.426656533888, 10743.033127018567, 10539.31494452862, 10608.522616482276, 10609.31039482435, 10610.946945265263, 10620.681379558626, 7555.055618047744, 10258.17934565233, 10445.112165465944, 10514.789704205874, 10531.578736850517, 10105.595776168944, 10408.427536485491, 10415.739606509822, 10418.062374386342, 10422.426766527838, 39662.0, 40061.59938400608, 40089.19821603552, 40123.0, 40181.39727904084, 3033.196203006205, 3139.1681204480833, 3355.328934213143, 3458.4985177149174, 3572.1248294167835, 30796.2300942476, 30847.228819279473, 31113.22216944572, 31410.685893141006, 32329.51505727416, 3415.334009868077, 3501.9824900875333, 3546.131197856542, 3589.5513060867393, 3622.203115314653, 268425.5523390973, 269230.0, 270044.0, 270313.0, 270320.0, 33321.16697082544, 34271.657283426845, 34378.65621343755, 55974.72012639911, 56231.0, 34462.82768586141, 34752.47871281933, 34812.477812832825, 34962.65037349594, 35027.649723502946, 14172.149671019772, 14495.20276384809, 14508.564743057725, 14510.564683059525, 14515.564533064025, 10081.344712593658, 10241.129503992095, 10362.171026317876, 10411.375317480975, 10551.525181366856, 8027.317677997436, 8066.072401673765, 8085.231902969209, 8140.308073813674, 8149.511029338259, 3084.599002129709, 3091.7062879026457, 3128.4212420702243, 3135.0751528299206, 3156.71589556941, 836.7698882807276, 844.8479273730787, 906.8685040669066, 951.7002144324516, 975.8633791269228, 29020.564691529646, 29062.41875162485, 29197.416051678847, 29567.40865182684, 29627.407451850842, 986.7187851462298, 1015.4059874973224, 1019.7960407918446, 1035.233926894093, 1048.4076496779296, 14837.0, 15521.223938803014, 15542.844571554366, 15629.843701562839, 15767.921160394355, 404359.0, 409564.0, 410656.0, 410791.0, 412114.0, 50957.49042509602, 52243.73878130585, 52267.477325227024, 52271.0, 52369.738151309, 1017.1608423051013, 1175.6884425627045, 1206.252123683322, 1208.5528354508888, 1211.6062279759115, 26418.207453776417, 29677.258068548457, 29958.0, 29972.550411743407, 30420.0, 42642.78678606587, 42976.0, 43071.0, 43100.137997240505, 43147.352789707555, 288041.0, 292348.0, 294161.0, 295733.0, 296058.0, 22645.660315094956, 24957.87521062383, 25687.614685779354, 26201.868990654926, 26229.868850655625, 42676.0, 42850.571494285905, 43558.346624801285, 43693.0, 44518.332225016005, 36431.27137457183, 36747.63252367549, 37287.44068839024, 37562.62437375591, 37823.81088094542, 37230.25539489249, 37550.73883745572, 37809.432858506596, 37934.43098353582, 38253.42619860649, 36524.26951461009, 36743.0, 37783.0, 38278.42582361211, 38318.23363532769, 32058.198545035626, 33522.0, 34196.82901585476, 34756.65243347635, 34885.82557087198, 26167.869160654074, 26180.476390472464, 26371.0, 26622.733772662024, 26649.0], "value": 17309.799792498143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.72788136778786, 371.71192325947396, 375.27571786452154, 387.0362796636373, 388.19449641992856, 16392.508224753263, 17347.653046939053, 18184.81815181848, 18185.7272140918, 18191.272349106053, 16567.66864662706, 17301.82698173018, 17327.0, 17681.381151659694, 17794.0, 4615.399998000264, 4774.427068751737, 4806.4472585652575, 4810.639202059842, 4814.783334749927, 3503.1242189452637, 3670.724695647824, 4693.272542755878, 5731.455511726379, 6025.698715064251, 6034.306054803688, 6050.2739671239515, 6055.606385584945, 6069.544784141207, 6088.939110608903, 5508.614396992192, 5541.476094074142, 5541.750621222014, 5545.611807173499, 5567.49892509674, 3383.9830800846084, 4978.153713868651, 5295.443978382292, 5304.310439642822, 5351.143816989302, 12644.304563249018, 12645.0, 12682.239065655998, 14259.0, 20977.0, 1934.980650193494, 1950.9414717558495, 1955.1690531524046, 1994.9002549872448, 2034.1456588232977, 9594.42433453995, 9603.855942160873, 10868.45657717111, 11493.942530287379, 13644.386002629906, 1710.5638062294036, 1753.4126067767313, 1771.5393997560598, 1777.5378401615544, 1793.8744287899851, 64286.3571364285, 72032.0, 72927.27072729258, 73671.26328736698, 74419.62790186069, 10118.848217276747, 10125.696229113139, 10145.94927025375, 10297.330673506198, 10325.173986081096, 13952.16287022782, 14044.648883777783, 14085.788713169311, 14088.084274522123, 14089.929550352183, 7238.565686058853, 7370.5946172961, 7376.151742549569, 7381.889271660822, 7433.628318584049, 6737.258901520829, 6864.828379290558, 6887.690053947585, 7039.120109986252, 7066.364027237573, 4309.375140604595, 4329.891752706208, 4343.869683909487, 4363.0, 4459.710118842265, 1659.510444418875, 1744.0, 1749.2740512687092, 1792.4712209898112, 1832.7709036370454, 750.0436942897849, 844.1980118886959, 879.2043200903192, 916.6425094213262, 921.6451666108596, 16170.838291616934, 16694.332226711038, 17460.388886388915, 17783.46649600514, 17869.0, 863.0592654007162, 894.2979760887672, 895.6999405199201, 900.4462255712821, 912.0, 9332.90667093334, 9785.510724463891, 9789.608415663437, 9793.412395256308, 9870.605175792887, 373087.0, 374094.0, 381064.0, 394814.0, 395811.0, 81189.0, 81271.59364203141, 81549.18450815415, 81822.0, 82716.58641706753, 889.1375365895071, 903.9005709371963, 910.39003867409, 921.4195057114109, 928.4661319741176, 15439.768403473732, 15567.532974010797, 15590.22048897528, 15664.843351566795, 15853.286602102702, 38521.61478385292, 38971.415428769156, 39257.41113883237, 40479.392809107296, 41024.79487602543, 51859.0, 53684.19473707976, 55280.44719552753, 55409.44590554043, 55409.72295138498, 7327.959035394174, 7390.928315394345, 7418.1839997599145, 7657.195994420728, 7707.190744971922, 10258.384496930208, 10919.181061420584, 10923.289986150874, 11172.162087843282, 11184.328940263285, 7344.7003689301455, 7829.530228186327, 9675.758106047406, 9757.512124393894, 9946.900530994597, 9693.41839489633, 9778.217742580435, 9867.654632087842, 10022.749431264278, 10132.392056476636, 6822.806008948513, 9746.658866939622, 10007.949960250153, 10091.0, 10429.530671119666, 5882.088276317178, 6081.483073938764, 6313.842153946188, 6460.580062295936, 7428.1086269647985, 8534.658613655633, 9056.773580660536, 9204.539773011456, 9421.104995025462, 9640.903590964], "value": 8085.514263703981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.0738294268426, 1390.1381143690912, 1435.3110506956662, 1651.8430749078834, 1676.0446545469072, 6981.68582413792, 7366.8526629467515, 7418.851622967537, 7433.293837085468, 7443.962780186098, 6240.531960102988, 6366.872662546746, 6368.267649220341, 6573.769918052869, 7306.96346518268, 1878.6618408686397, 2063.2778527515325, 2093.225506562574, 2114.027547328231, 2116.5872654832315, 2067.9896600516977, 2072.202202152167, 2078.542720601465, 2080.5422806982438, 2082.2816128435666, 1856.3595559531939, 2118.248021952208, 2165.4694599823065, 2169.0889826272924, 2171.359448962552, 1829.0945981739058, 1845.7600511933497, 1851.7407562941266, 2058.5574101568236, 2129.9254526091668, 2124.66005439129, 2171.5005548723884, 2171.6308227601344, 2172.92394766184, 2173.5435558532727, 7103.431725461951, 7178.138623365229, 7181.8922716159295, 7184.856302873965, 7189.496735228536, 4287.3140297552245, 4449.043455657049, 4455.46534415869, 4456.398386217851, 4458.08609235106, 2779.3190668286197, 2831.164806382122, 2866.7419932205994, 2871.0, 2885.2498350428828, 826.446280991737, 838.1869586501084, 858.8625819868854, 862.1895418306772, 864.8616221404608, 9526.523673816351, 9704.02959704024, 9707.174890134347, 9762.219022478255, 9767.658131965347, 3891.883243502643, 4001.679865610744, 4077.898052548651, 4128.649064829463, 4138.234426631083, 8427.41008129425, 8439.451435656663, 8443.155684431507, 8448.0, 8461.23848853606, 4372.256716358227, 4375.956240437618, 4375.978120109379, 4380.824767009348, 4381.474223093249, 3911.7482405629926, 4050.1697152084344, 4059.9594004060173, 4067.8576249831112, 4068.2677118119022, 3379.6620337966497, 3426.7258619310396, 3501.3347463981763, 3503.737219708481, 3507.6667716566885, 1127.182792475465, 1142.3431526872082, 1148.2306854407566, 1162.4885050577718, 1168.8480497535265, 817.7710241132493, 825.005867929144, 840.5293035899915, 852.6717213872704, 857.3698331726176, 4692.366530518405, 4722.95277047232, 4880.926786098212, 4949.925751113737, 4981.078500477351, 409.60063937661135, 426.2561829607355, 430.36521131331034, 903.2833160005995, 939.196986576481, 2747.9450410991667, 3360.7143392811395, 3536.717062634983, 3655.3420384331075, 3708.8145592720257, 8566.957165214259, 8659.393842431095, 8704.086070962458, 8708.825823483621, 8728.127187281221, 7832.686692532349, 8357.414980951397, 8721.869171962426, 8737.082606326372, 8819.250363719155, 6542.0, 6622.536422450478, 6655.301193374626, 6664.400203981665, 6685.0, 4909.042736666316, 4974.9751251243515, 5074.340335756328, 5111.539961403491, 5133.0, 7701.422393320411, 7923.128455869965, 7923.960380198062, 7931.405144614062, 8020.197980201933, 7981.361491080584, 8093.55485448306, 8107.067687216046, 8145.429819912786, 8156.0, 6245.344238855037, 6276.152719383008, 6284.020533531922, 6337.397947195009, 6358.936410635834, 6816.2842901496615, 6900.482463815133, 6905.447564194752, 6909.343612356818, 6913.75801846933, 6233.758505088168, 6339.175907132043, 6384.329645387354, 6418.101465794886, 6450.451711604566, 6160.476359509492, 6199.287081985449, 6199.318075011655, 6304.085907543472, 6323.146375239284, 5724.885502289848, 5727.914081288701, 5777.075667893117, 5778.595498315078, 5783.7397317119985, 3459.308138372285, 4318.740875547477, 4328.653707703439, 4330.090680957034, 4335.501417337046, 4346.717463364871, 4363.0, 4365.0, 4375.343698445258, 4470.754108524063], "value": 3849.8067498792843, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.39277071160194, 303.4886216724819, 304.3927364907011, 306.080228912119, 307.6200891898505, 4862.319275301456, 4875.951240487594, 4892.266160075986, 4909.386326709161, 4964.627652926027, 4794.9280760788615, 4797.448293446255, 4874.463808981018, 4881.682690625115, 4883.829065982692, 3574.8391322390426, 3677.705783537324, 3729.272791805599, 3818.331791936414, 3825.718384341235, 3764.682361173581, 3792.5259342582176, 3805.8962900758747, 3806.4290356446477, 3828.272628200647, 3603.9099022524383, 3763.34141525232, 3763.927280724994, 3768.359378905579, 3786.469894214812, 3653.4702468142236, 3773.3585290500682, 3818.8472461101524, 3826.2730081284744, 3831.7700937943814, 3364.226227967581, 3369.1745522346937, 3373.3590617782543, 3374.8481318340487, 3393.389189945809, 4763.309320148559, 4767.0, 4772.947632518016, 4778.2115950868065, 4781.342273161034, 4260.403543503912, 4347.478302603676, 4364.978175109136, 4385.934210986838, 4399.824007039714, 5055.4186268579215, 5059.392872855244, 5060.671056381323, 5102.336696229503, 5116.744162791845, 3693.3351996640863, 3697.85208591656, 3711.4247291669835, 3716.739828212026, 3725.807741522714, 5113.565346945476, 5238.397584277857, 5251.396089449724, 5316.893662126736, 5318.760655770499, 3728.8694895678523, 3748.7066961898104, 3759.8496060157836, 3765.3787125124595, 3769.1707824278615, 3794.6015668354976, 3805.9048523786573, 3837.175007373428, 3856.4162011452395, 3856.6721828644318, 4337.37108119321, 4360.912781744348, 4362.71642343253, 4365.2797288447055, 4371.737695738266, 4854.956184420366, 5035.144025515671, 5037.899242015139, 5044.646874718735, 5051.87370315745, 1300.2133709106045, 1310.0763961407185, 1321.914075585084, 1321.9537316193887, 1322.9019913471795, 842.5424016451573, 859.8864470510704, 914.3005600715458, 945.6312038305067, 945.6595625574926, 4428.955710442854, 4499.955000449953, 4505.144022635689, 4508.233600287959, 4513.954860451419, 5106.234064890296, 5149.304843846033, 5177.896442071137, 5207.766194974027, 5212.166053431432, 4249.511306199826, 4378.846740364072, 4440.800163992628, 4459.866204013885, 4512.977435112869, 2758.4896794093156, 2763.2677340504783, 2768.473989941945, 2783.554631258989, 2789.4978903797105, 4938.827141050046, 4972.403311602631, 4988.0, 4995.400551933792, 5003.949960500348, 5095.337606111182, 5340.599455040888, 5348.304720386403, 5380.488853558905, 5382.19696401702, 4978.0, 4991.700497970133, 4994.525520075587, 5001.599552125411, 5006.89986200274, 5220.686758794484, 5222.3472065991755, 5227.712475813867, 5229.529342359206, 5233.790648374023, 4857.902841943071, 5137.743112844418, 5154.14956532168, 5167.664101833519, 5197.298364720715, 4955.900881982268, 4963.677360971526, 4966.081274964144, 4975.228839529878, 4979.850604481871, 3664.212194378115, 3680.300742858848, 3692.981535092415, 3698.6116457771545, 3712.1647629283193, 5155.200943853709, 5224.242484839753, 5249.527542521038, 5272.314599102246, 5297.443768404262, 5070.923936141035, 5074.0, 5099.286099945936, 5102.515261050195, 5114.2840002400435, 5088.872778180427, 5109.6678715883345, 5125.128728116304, 5136.383633963948, 5153.7165455898785, 4779.617630589614, 4931.432885218246, 5020.3724534433195, 5031.320771695774, 5049.444561098349, 4978.6763860348965, 5122.6926384415565, 5137.588992880485, 5140.228965655182, 5155.845324640267, 4990.8253211137435, 5020.221865610836, 5062.620303477181, 5064.873378165576, 5076.695398276115], "value": 3850.2760805240036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.8845190543562, 722.2632914427285, 730.5981710059469, 741.9538450784395, 752.1312883619421, 11719.472623731932, 12673.556425525108, 12690.238585684865, 12693.428795704192, 12700.809487857667, 12080.577179798709, 12217.328046957413, 12224.816627750568, 12227.14409991301, 12240.693982650439, 609.8140067279467, 745.4260219630881, 769.8498792735419, 771.2326235395789, 776.6466257852692, 769.3268390158611, 772.9188435214306, 775.6587101675273, 775.8836174573831, 777.8872063550782, 646.7800947677789, 833.2500749325625, 834.586879494651, 835.3609488741118, 835.4152093534519, 788.1251810490351, 807.0, 808.1877712898558, 813.7436707437139, 814.1695470619934, 603.6317846113867, 603.7373742422017, 603.7766026570197, 604.0606856338392, 604.3291945940003, 12472.314022728746, 12495.562655307112, 12509.937450312782, 12523.812142817866, 12524.060695447877, 12737.617871463872, 12992.0, 13060.738785224243, 13120.540781072617, 13466.326683665875, 11660.104531344554, 11707.94146029273, 11809.114316426303, 11812.468438920183, 12049.518019279218, 4052.5542190359038, 4085.836566537335, 4101.446304748881, 4110.958890411088, 4111.8560850370095, 60959.695201524155, 61057.6947115266, 61618.0, 62811.68594157046, 62822.685886570274, 9842.311038227399, 10175.135113515435, 10206.948965255126, 10247.795044099077, 10288.639897603547, 19074.332398366267, 19105.90447047756, 19162.904185479263, 19199.808001920082, 19206.039698015044, 5826.737796799154, 5849.268841394825, 5870.706464676835, 5879.52963762898, 6022.548308876764, 3579.480975258573, 3587.515685382492, 3661.176235347079, 3703.2223233121344, 3798.3352913240074, 2590.963614554156, 2707.2555047362134, 2708.9593656095176, 2710.471458065698, 2711.755941965193, 457.9885502862455, 483.6324393460995, 499.86003918902765, 515.1680036740637, 577.8006587727227, 509.2844553402475, 511.882267078574, 529.9152135658276, 531.1528112660301, 592.431265984651, 8133.837323253501, 8302.8339433211, 8387.874181887277, 8451.830963380698, 8505.53219572917, 584.2054805464564, 595.1875689683551, 595.8152972578428, 602.5902386377305, 615.6429271022796, 4039.2123535910805, 4097.221527909687, 4154.813033413504, 4166.958330416717, 4254.680898932531, 62038.689806550676, 104485.0, 106289.0, 109349.0, 110352.0, 91632.54183729038, 92307.53846230726, 93638.0, 93641.5317923406, 93860.0, 49455.25817112746, 57464.71267643635, 78393.0, 78806.21193788103, 80047.30090460477, 8066.959665201636, 8087.8382432349845, 8180.46826956246, 8197.221263979913, 8208.179182081984, 47061.0, 48666.51333486762, 50044.24933626071, 50261.98952041877, 51462.74268628633, 63208.36791632025, 71396.28603714104, 71661.64169179121, 71900.64049679718, 73462.26537734768, 11162.107031437628, 11289.491972861406, 11512.13658975597, 11619.767604648028, 11909.464074116513, 11943.82084268754, 12042.397880105784, 12082.879171208175, 12277.631671049883, 12397.070219733376, 11179.66461006171, 11323.0, 11546.653600392001, 11680.299182049104, 12043.578474753342, 11141.220114592224, 11344.546218151181, 11851.644450666494, 11862.881371186179, 11862.881371186179, 10630.946845265724, 10995.450227488753, 11103.72240693989, 11174.720631984266, 11176.776464470828, 8806.559672016501, 9307.743454633537, 9408.905910941077, 9421.152096311365, 9565.713028609152, 9326.626934922528, 9692.75768105803, 9790.314677972758, 9892.151734787858, 9905.405675659484], "value": 6560.338853584779, "unit": "score"}], "name": "dromaeo_css", "value": 6291.240353205747, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 116.0, 114.0, 114.0, 115.0, 113.0, 114.0, 114.0, 116.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 123.0, 124.0, 167.0, 123.0, 125.0, 148.0, 124.0, 176.0, 129.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 161.0, 164.0, 164.0, 164.0, 162.0, 162.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 78.0, 122.0, 76.0, 77.0, 77.0, 77.0, 102.0, 77.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 114.0, 85.0, 85.0, 85.0, 121.0, 84.0, 86.0, 137.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 63.0, 64.0, 64.0, 63.0, 62.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 65.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 78.0, 79.0, 78.0, 79.0, 77.0, 84.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 128.0, 139.0, 127.0, 138.0, 132.0, 130.0, 164.0, 129.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 167.0, 173.0, 163.0, 168.0, 168.0, 167.0, 168.0, 163.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 66.0, 67.0, 66.0, 65.0, 68.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1529.2000000000003, "alertThreshold": 2.0}]} 10:20:08 INFO - Return code: 0 10:20:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:20:08 INFO - # TBPL SUCCESS # 10:20:08 INFO - Running post-action listener: _resource_record_post_action 10:20:08 INFO - ##### 10:20:08 INFO - ##### Finished run-tests step (success) 10:20:08 INFO - ##### 10:20:08 INFO - Running post-run listener: _resource_record_post_run 10:20:08 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546222080; Read time: 8; Write time: 499056 10:20:08 INFO - TinderboxPrint: CPU usage
13.0% 10:20:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 10:20:08 INFO - TinderboxPrint: I/O write bytes / time
546,222,080 / 499,056 10:20:08 INFO - TinderboxPrint: CPU idle
8,335.3 (87.0%) 10:20:08 INFO - TinderboxPrint: CPU user
1,199.6 (12.5%) 10:20:08 INFO - TinderboxPrint: Swap in / out
0 / 0 10:20:08 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 258453504; Read time: 0; Write time: 282804 10:20:08 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 287391744; Read time: 0; Write time: 216200 10:20:08 INFO - Running post-run listener: _upload_blobber_files 10:20:08 INFO - Blob upload gear active. 10:20:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:20:08 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 10:20:08 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'] 10:20:08 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 10:20:09 INFO - (blobuploader) - INFO - Open directory for files ... 10:20:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:20:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:20:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:09 INFO - (blobuploader) - INFO - Done attempting. 10:20:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:20:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:13 INFO - (blobuploader) - INFO - Done attempting. 10:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:13 INFO - (blobuploader) - INFO - Done attempting. 10:20:13 INFO - (blobuploader) - INFO - Iteration through files over. 10:20:13 INFO - Return code: 0 10:20:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:20:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:20:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a577305af95204e84e11fd24b5157b2e981ca4e43b705fd27d2bd91a3c2ad188003113a27c35dd0e015d04c7bb8d0e1ff45bb789385af1542bb29c89d9a2fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1f3b2bc56df44b3e0c6c597b2f51903c6bbbb1b89353075989d87194c56fb63f6eb2572855381a0100f8a0aa1ec2cf726a7b8a0e641a4887fe1c08f038588df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66cbe7b93b0935257526890a6c3d787accd44bc6f1b389330799dcbb9b3181be6b1cb681598946641e805b9621e756b5d607cf5bd37ae962fdf0149efa7e40ae"} 10:20:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:20:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:20:13 INFO - Contents: 10:20:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a577305af95204e84e11fd24b5157b2e981ca4e43b705fd27d2bd91a3c2ad188003113a27c35dd0e015d04c7bb8d0e1ff45bb789385af1542bb29c89d9a2fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1f3b2bc56df44b3e0c6c597b2f51903c6bbbb1b89353075989d87194c56fb63f6eb2572855381a0100f8a0aa1ec2cf726a7b8a0e641a4887fe1c08f038588df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66cbe7b93b0935257526890a6c3d787accd44bc6f1b389330799dcbb9b3181be6b1cb681598946641e805b9621e756b5d607cf5bd37ae962fdf0149efa7e40ae"} 10:20:13 INFO - Running post-run listener: copy_logs_to_upload_dir 10:20:13 INFO - Copying logs to upload dir... 10:20:13 INFO - mkdir: /builds/slave/test/build/upload/logs 10:20:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.261381 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-13 10:20:13.300024) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 10:20:13.303397) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 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/2a577305af95204e84e11fd24b5157b2e981ca4e43b705fd27d2bd91a3c2ad188003113a27c35dd0e015d04c7bb8d0e1ff45bb789385af1542bb29c89d9a2fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1f3b2bc56df44b3e0c6c597b2f51903c6bbbb1b89353075989d87194c56fb63f6eb2572855381a0100f8a0aa1ec2cf726a7b8a0e641a4887fe1c08f038588df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66cbe7b93b0935257526890a6c3d787accd44bc6f1b389330799dcbb9b3181be6b1cb681598946641e805b9621e756b5d607cf5bd37ae962fdf0149efa7e40ae"} build_url:https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/VagQ7pt7THiXnW_7zSQLRw/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/2a577305af95204e84e11fd24b5157b2e981ca4e43b705fd27d2bd91a3c2ad188003113a27c35dd0e015d04c7bb8d0e1ff45bb789385af1542bb29c89d9a2fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1f3b2bc56df44b3e0c6c597b2f51903c6bbbb1b89353075989d87194c56fb63f6eb2572855381a0100f8a0aa1ec2cf726a7b8a0e641a4887fe1c08f038588df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66cbe7b93b0935257526890a6c3d787accd44bc6f1b389330799dcbb9b3181be6b1cb681598946641e805b9621e756b5d607cf5bd37ae962fdf0149efa7e40ae"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 10:20:13.328043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:20:13.328335) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468425484.418904-1218369583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:20:13.412533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 10:20:13.412827) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 10:20:13.413092) ========= ========= Total master_lag: 0.20 =========