builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1468512513.64 results: success (0) buildid: 20160714075544 builduid: 205664bbcf2e42939e5390374d6bdbd2 revision: d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.644984) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.645455) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.645739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.662957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.663276) ========= 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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-14 09:08: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% 46.5M=0s 2016-07-14 09:08:33 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163907 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.842076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.842420) ========= 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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022971 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.895871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:33.896461) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a --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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-14 09:08:34,081 truncating revision to first 12 chars 2016-07-14 09:08:34,082 Setting DEBUG logging. 2016-07-14 09:08:34,082 attempt 1/10 2016-07-14 09:08:34,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d5cf90154e67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 09:08:34,394 unpacking tar archive at: autoland-d5cf90154e67/testing/mozharness/ program finished with exit code 0 elapsedTime=0.719274 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:34.631434) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:34.631757) ========= script_repo_revision: d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:34.632166) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:34.632449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 09:08:34.642116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-14 09:08:34.642451) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:08:34 INFO - MultiFileLogger online at 20160714 09:08:34 in /builds/slave/test 09:08:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:08:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:08:34 INFO - {'append_to_log': False, 09:08:34 INFO - 'base_work_dir': '/builds/slave/test', 09:08:34 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:08:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:08:34 INFO - 'branch': 'Autoland-Non-PGO', 09:08:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:08:34 INFO - 'config_files': ('talos/linux_config.py',), 09:08:34 INFO - 'default_actions': ('clobber', 09:08:34 INFO - 'read-buildbot-config', 09:08:34 INFO - 'download-and-extract', 09:08:34 INFO - 'populate-webroot', 09:08:34 INFO - 'create-virtualenv', 09:08:34 INFO - 'install', 09:08:34 INFO - 'run-tests'), 09:08:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:08:34 INFO - 'download_minidump_stackwalk': True, 09:08:34 INFO - 'download_symbols': 'ondemand', 09:08:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:08:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:08:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:08:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:08:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:08:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:08:34 INFO - 'installer_path': 'installer.exe', 09:08:34 INFO - 'log_level': 'info', 09:08:34 INFO - 'log_name': 'talos', 09:08:34 INFO - 'log_to_console': True, 09:08:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:08:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:08:34 INFO - 'opt_config_files': (), 09:08:34 INFO - 'pip_index': False, 09:08:34 INFO - 'sps_profile': False, 09:08:34 INFO - 'sps_profile_interval': 0, 09:08:34 INFO - 'suite': 'dromaeojs', 09:08:34 INFO - 'system_bits': '32', 09:08:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:08:34 INFO - 'title': 'talos-linux64-ix-053', 09:08:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:08:34 INFO - 'use_talos_json': True, 09:08:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:08:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:08:34 INFO - 'work_dir': 'build'} 09:08:34 INFO - ##### 09:08:34 INFO - ##### Running clobber step. 09:08:34 INFO - ##### 09:08:34 INFO - Running pre-action listener: _resource_record_pre_action 09:08:34 INFO - Running main action method: clobber 09:08:34 INFO - rmtree: /builds/slave/test/build 09:08:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:08:35 INFO - Running post-action listener: _resource_record_post_action 09:08:35 INFO - ##### 09:08:35 INFO - ##### Finished clobber step (success) 09:08:35 INFO - ##### 09:08:35 INFO - ##### 09:08:35 INFO - ##### Running read-buildbot-config step. 09:08:35 INFO - ##### 09:08:35 INFO - Running pre-action listener: _resource_record_pre_action 09:08:35 INFO - Running main action method: read_buildbot_config 09:08:35 INFO - Using buildbot properties: 09:08:35 INFO - { 09:08:35 INFO - "project": "", 09:08:35 INFO - "product": "firefox", 09:08:35 INFO - "script_repo_revision": "production", 09:08:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:08:35 INFO - "repository": "", 09:08:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:08:35 INFO - "buildid": "20160714075544", 09:08:35 INFO - "pgo_build": "False", 09:08:35 INFO - "basedir": "/builds/slave/test", 09:08:35 INFO - "buildnumber": 59, 09:08:35 INFO - "slavename": "talos-linux64-ix-053", 09:08:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:08:35 INFO - "platform": "ubuntu64_hw", 09:08:35 INFO - "scheduler": "tests-autoland-linux64-talos", 09:08:35 INFO - "branch": "autoland", 09:08:35 INFO - "repo_path": "integration/autoland", 09:08:35 INFO - "revision": "d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a", 09:08:35 INFO - "stage_platform": "linux64", 09:08:35 INFO - "builduid": "205664bbcf2e42939e5390374d6bdbd2", 09:08:35 INFO - "slavebuilddir": "test" 09:08:35 INFO - } 09:08:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:08:35 INFO - Running post-action listener: _resource_record_post_action 09:08:35 INFO - ##### 09:08:35 INFO - ##### Finished read-buildbot-config step (success) 09:08:35 INFO - ##### 09:08:35 INFO - ##### 09:08:35 INFO - ##### Running download-and-extract step. 09:08:35 INFO - ##### 09:08:35 INFO - Running pre-action listener: _resource_record_pre_action 09:08:35 INFO - Running main action method: download_and_extract 09:08:35 INFO - mkdir: /builds/slave/test/build/tests 09:08: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:08:35 INFO - https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:36 INFO - Downloaded 1361 bytes. 09:08:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:08:36 INFO - Using the following test package requirements: 09:08:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:08:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:08:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'jsshell-linux-x86_64.zip'], 09:08:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:08:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:08:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:08:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:08:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:08:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:08: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:08:36 INFO - https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:43 INFO - Downloaded 22756264 bytes. 09:08:43 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:08:43 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:08:44 INFO - Return code: 0 09:08:44 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:08:44 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:08:44 INFO - https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:47 INFO - Downloaded 22756264 bytes. 09:08:47 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:08:47 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:08:49 INFO - Return code: 0 09:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:49 INFO - https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:08:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:08:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:08:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:50 INFO - Downloaded 14403317 bytes. 09:08:50 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:08:50 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:08:50 INFO - Return code: 0 09:08: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:08:50 INFO - https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:08:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:08:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:08:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/axLEqki6T1G9oOxRS8GXKg/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:08:52 INFO - Downloaded 57047698 bytes. 09:08:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:08:52 INFO - mkdir: /builds/slave/test/properties 09:08:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:08:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:08:52 INFO - Contents: 09:08:52 INFO - build_url:https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:08:53 INFO - Running post-action listener: _resource_record_post_action 09:08:53 INFO - Running post-action listener: set_extra_try_arguments 09:08:53 INFO - ##### 09:08:53 INFO - ##### Finished download-and-extract step (success) 09:08:53 INFO - ##### 09:08:53 INFO - ##### 09:08:53 INFO - ##### Running populate-webroot step. 09:08:53 INFO - ##### 09:08:53 INFO - Running pre-action listener: _resource_record_pre_action 09:08:53 INFO - Running main action method: populate_webroot 09:08:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:08:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:08:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:08:53 INFO - '--tppagecycles', 09:08:53 INFO - '7'], 09:08:53 INFO - 'tests': ['tsvgm']}}, 09:08:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:08:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:08:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:08:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:08:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['damp', 'tps']}, 09:08:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'tests': ['damp', 'tps']}, 09:08:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['dromaeo_dom']}, 09:08:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:08:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['basic_compositor_video']}, 09:08:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:08:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['a11yr', 09:08:53 INFO - 'ts_paint', 09:08:53 INFO - 'tpaint', 09:08:53 INFO - 'sessionrestore', 09:08:53 INFO - 'sessionrestore_no_auto_restore', 09:08:53 INFO - 'tabpaint']}, 09:08:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['tsvgx', 09:08:53 INFO - 'tsvgr_opacity', 09:08:53 INFO - 'tart', 09:08:53 INFO - 'tscrollx', 09:08:53 INFO - 'cart']}, 09:08:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:08:53 INFO - 'tsvgr_opacity', 09:08:53 INFO - 'tart', 09:08:53 INFO - 'tscrollx', 09:08:53 INFO - 'cart']}, 09:08:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'talos_options': ['--disable-e10s'], 09:08:53 INFO - 'tests': ['tp5o']}, 09:08:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'tests': ['tp5o']}, 09:08:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'talos_options': ['--disable-e10s', 09:08:53 INFO - '--xperf_path', 09:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:53 INFO - 'tests': ['tp5n']}, 09:08:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:53 INFO - 'talos_options': ['--xperf_path', 09:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:53 INFO - 'tests': ['tp5n']}}, 09:08:53 INFO - 'talos.zip': {'path': '', 09:08:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:08:53 INFO - Running post-action listener: _resource_record_post_action 09:08:53 INFO - ##### 09:08:53 INFO - ##### Finished populate-webroot step (success) 09:08:53 INFO - ##### 09:08:53 INFO - ##### 09:08:53 INFO - ##### Running create-virtualenv step. 09:08:53 INFO - ##### 09:08:53 INFO - Running pre-action listener: _resource_record_pre_action 09:08:53 INFO - Running main action method: create_virtualenv 09:08:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:08:53 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:08:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:08:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:08:53 INFO - Using real prefix '/usr' 09:08:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:08:53 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:55 INFO - Installing pip.................done. 09:08:55 INFO - Return code: 0 09:08:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08: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:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:08: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', 'pip>=1.5'] in /builds/slave/test/build 09:08: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 pip>=1.5 09:08:55 INFO - Using env: {'DISPLAY': ':0', 09:08:55 INFO - 'HOME': '/home/cltbld', 09:08:55 INFO - 'LANG': 'en_US.UTF-8', 09:08:55 INFO - 'LANGUAGE': 'en_US:en', 09:08:55 INFO - 'LOGNAME': 'cltbld', 09:08:55 INFO - 'MAIL': '/var/mail/cltbld', 09:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:55 INFO - 'MOZ_NO_REMOTE': '1', 09:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:55 INFO - 'NO_EM_RESTART': '1', 09:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:55 INFO - 'PWD': '/builds/slave/test', 09:08:55 INFO - 'SHELL': '/bin/bash', 09:08:55 INFO - 'SHLVL': '1', 09:08:55 INFO - 'TERM': 'linux', 09:08:55 INFO - 'TMOUT': '86400', 09:08:55 INFO - 'USER': 'cltbld', 09:08:55 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:55 INFO - '_': '/tools/buildbot/bin/python'} 09:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:56 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:08:56 INFO - Cleaning up... 09:08:56 INFO - Return code: 0 09:08:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:56 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:08:56 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:08:56 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:08:56 INFO - Using env: {'DISPLAY': ':0', 09:08:56 INFO - 'HOME': '/home/cltbld', 09:08:56 INFO - 'LANG': 'en_US.UTF-8', 09:08:56 INFO - 'LANGUAGE': 'en_US:en', 09:08:56 INFO - 'LOGNAME': 'cltbld', 09:08:56 INFO - 'MAIL': '/var/mail/cltbld', 09:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:56 INFO - 'MOZ_NO_REMOTE': '1', 09:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:56 INFO - 'NO_EM_RESTART': '1', 09:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:56 INFO - 'PWD': '/builds/slave/test', 09:08:56 INFO - 'SHELL': '/bin/bash', 09:08:56 INFO - 'SHLVL': '1', 09:08:56 INFO - 'TERM': 'linux', 09:08:56 INFO - 'TMOUT': '86400', 09:08:56 INFO - 'USER': 'cltbld', 09:08:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:56 INFO - '_': '/tools/buildbot/bin/python'} 09:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:56 INFO - Downloading/unpacking psutil>=3.1.1 09:08:56 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:08:56 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:08:56 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:08:56 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:08:56 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:08:56 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:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:58 INFO - Installing collected packages: psutil 09:08:58 INFO - Running setup.py install for psutil 09:08:58 INFO - building 'psutil._psutil_linux' extension 09:08:58 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:08:58 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:08:58 INFO - building 'psutil._psutil_posix' extension 09:08:58 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:08:58 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:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:58 INFO - Successfully installed psutil 09:08:58 INFO - Cleaning up... 09:08:58 INFO - Return code: 0 09:08:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:58 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:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:58 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:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:58 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:08:58 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:08:58 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:08:58 INFO - Using env: {'DISPLAY': ':0', 09:08:58 INFO - 'HOME': '/home/cltbld', 09:08:58 INFO - 'LANG': 'en_US.UTF-8', 09:08:58 INFO - 'LANGUAGE': 'en_US:en', 09:08:58 INFO - 'LOGNAME': 'cltbld', 09:08:58 INFO - 'MAIL': '/var/mail/cltbld', 09:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:58 INFO - 'MOZ_NO_REMOTE': '1', 09:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:58 INFO - 'NO_EM_RESTART': '1', 09:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:58 INFO - 'PWD': '/builds/slave/test', 09:08:58 INFO - 'SHELL': '/bin/bash', 09:08:58 INFO - 'SHLVL': '1', 09:08:58 INFO - 'TERM': 'linux', 09:08:58 INFO - 'TMOUT': '86400', 09:08:58 INFO - 'USER': 'cltbld', 09:08:58 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:58 INFO - '_': '/tools/buildbot/bin/python'} 09:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:59 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:08:59 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:08:59 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:08:59 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:08:59 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:08:59 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:09:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:01 INFO - Installing collected packages: mozsystemmonitor 09:09:01 INFO - Running setup.py install for mozsystemmonitor 09:09:01 INFO - Successfully installed mozsystemmonitor 09:09:01 INFO - Cleaning up... 09:09:01 INFO - Return code: 0 09:09:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:09:01 INFO - Using env: {'DISPLAY': ':0', 09:09:01 INFO - 'HOME': '/home/cltbld', 09:09:01 INFO - 'LANG': 'en_US.UTF-8', 09:09:01 INFO - 'LANGUAGE': 'en_US:en', 09:09:01 INFO - 'LOGNAME': 'cltbld', 09:09:01 INFO - 'MAIL': '/var/mail/cltbld', 09:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:01 INFO - 'MOZ_NO_REMOTE': '1', 09:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:01 INFO - 'NO_EM_RESTART': '1', 09:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:01 INFO - 'PWD': '/builds/slave/test', 09:09:01 INFO - 'SHELL': '/bin/bash', 09:09:01 INFO - 'SHLVL': '1', 09:09:01 INFO - 'TERM': 'linux', 09:09:01 INFO - 'TMOUT': '86400', 09:09:01 INFO - 'USER': 'cltbld', 09:09:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:01 INFO - '_': '/tools/buildbot/bin/python'} 09:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:01 INFO - Downloading/unpacking jsonschema==2.5.1 09:09:01 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:09:01 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:09:01 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:09:01 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:09:01 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:09:01 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:09:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:03 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:09:03 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:09:03 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:09:03 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:09:03 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:09:03 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:09:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:03 INFO - warning: no files found matching '*.txt' 09:09:03 INFO - no previously-included directories found matching 'build' 09:09:03 INFO - no previously-included directories found matching 'dist' 09:09:03 INFO - no previously-included directories found matching '.git*' 09:09:03 INFO - Installing collected packages: jsonschema, functools32 09:09:03 INFO - Running setup.py install for functools32 09:09:03 INFO - warning: no files found matching '*.txt' 09:09:03 INFO - no previously-included directories found matching 'build' 09:09:03 INFO - no previously-included directories found matching 'dist' 09:09:03 INFO - no previously-included directories found matching '.git*' 09:09:03 INFO - Successfully installed jsonschema functools32 09:09:03 INFO - Cleaning up... 09:09:03 INFO - Return code: 0 09:09:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:03 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:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:03 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:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:03 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:03 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:09:03 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:09:03 INFO - Using env: {'DISPLAY': ':0', 09:09:03 INFO - 'HOME': '/home/cltbld', 09:09:03 INFO - 'LANG': 'en_US.UTF-8', 09:09:03 INFO - 'LANGUAGE': 'en_US:en', 09:09:03 INFO - 'LOGNAME': 'cltbld', 09:09:03 INFO - 'MAIL': '/var/mail/cltbld', 09:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:03 INFO - 'MOZ_NO_REMOTE': '1', 09:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:03 INFO - 'NO_EM_RESTART': '1', 09:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:03 INFO - 'PWD': '/builds/slave/test', 09:09:03 INFO - 'SHELL': '/bin/bash', 09:09:03 INFO - 'SHLVL': '1', 09:09:03 INFO - 'TERM': 'linux', 09:09:03 INFO - 'TMOUT': '86400', 09:09:03 INFO - 'USER': 'cltbld', 09:09:03 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:03 INFO - '_': '/tools/buildbot/bin/python'} 09:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:04 INFO - Downloading/unpacking blobuploader==1.2.4 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 INFO - Downloading docopt-0.6.1.tar.gz 09:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:06 INFO - Running setup.py install for blobuploader 09:09:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:06 INFO - Running setup.py install for requests 09:09:07 INFO - Running setup.py install for docopt 09:09:07 INFO - Successfully installed blobuploader requests docopt 09:09:07 INFO - Cleaning up... 09:09:07 INFO - Return code: 0 09:09:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:07 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:07 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:09:07 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:09:07 INFO - Using env: {'DISPLAY': ':0', 09:09:07 INFO - 'HOME': '/home/cltbld', 09:09:07 INFO - 'LANG': 'en_US.UTF-8', 09:09:07 INFO - 'LANGUAGE': 'en_US:en', 09:09:07 INFO - 'LOGNAME': 'cltbld', 09:09:07 INFO - 'MAIL': '/var/mail/cltbld', 09:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:07 INFO - 'MOZ_NO_REMOTE': '1', 09:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:07 INFO - 'NO_EM_RESTART': '1', 09:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:07 INFO - 'PWD': '/builds/slave/test', 09:09:07 INFO - 'SHELL': '/bin/bash', 09:09:07 INFO - 'SHLVL': '1', 09:09:07 INFO - 'TERM': 'linux', 09:09:07 INFO - 'TMOUT': '86400', 09:09:07 INFO - 'USER': 'cltbld', 09:09:07 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:07 INFO - '_': '/tools/buildbot/bin/python'} 09:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:07 INFO - Running setup.py (path:/tmp/pip-aeFP7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:07 INFO - Running setup.py (path:/tmp/pip-DFDHxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:07 INFO - Running setup.py (path:/tmp/pip-5NlNTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:07 INFO - Running setup.py (path:/tmp/pip-_kWvoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:07 INFO - Running setup.py (path:/tmp/pip-1GIheo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:08 INFO - Running setup.py (path:/tmp/pip-1sbCH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:08 INFO - Running setup.py (path:/tmp/pip-yWN8X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:08 INFO - Running setup.py (path:/tmp/pip-AdfUL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:08 INFO - Running setup.py (path:/tmp/pip-TKty4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:08 INFO - Running setup.py (path:/tmp/pip-ra7VEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:08 INFO - Running setup.py (path:/tmp/pip-OBG_RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:08 INFO - Running setup.py (path:/tmp/pip-G4S8d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:08 INFO - Running setup.py (path:/tmp/pip-QFbs2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:08 INFO - Running setup.py (path:/tmp/pip-wo83mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:08 INFO - Running setup.py (path:/tmp/pip-XM_WVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:08 INFO - Running setup.py (path:/tmp/pip-8ly2Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:08 INFO - Running setup.py (path:/tmp/pip-7Z6LAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:08 INFO - Running setup.py install for manifestparser 09:09:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:08 INFO - Running setup.py install for mozcrash 09:09:09 INFO - Running setup.py install for mozdebug 09:09:09 INFO - Running setup.py install for mozdevice 09:09:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for mozfile 09:09:09 INFO - Running setup.py install for mozhttpd 09:09:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for mozinfo 09:09:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for mozInstall 09:09:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for mozleak 09:09:09 INFO - Running setup.py install for mozlog 09:09:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for moznetwork 09:09:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:09 INFO - Running setup.py install for mozprocess 09:09:10 INFO - Running setup.py install for mozprofile 09:09:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:10 INFO - Running setup.py install for mozrunner 09:09:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:10 INFO - Running setup.py install for mozscreenshot 09:09:10 INFO - Running setup.py install for moztest 09:09:10 INFO - Running setup.py install for mozversion 09:09:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:09:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:09:10 INFO - Cleaning up... 09:09:10 INFO - Return code: 0 09:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:10 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:10 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 09:09:10 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 09:09:10 INFO - Using env: {'DISPLAY': ':0', 09:09:10 INFO - 'HOME': '/home/cltbld', 09:09:10 INFO - 'LANG': 'en_US.UTF-8', 09:09:10 INFO - 'LANGUAGE': 'en_US:en', 09:09:10 INFO - 'LOGNAME': 'cltbld', 09:09:10 INFO - 'MAIL': '/var/mail/cltbld', 09:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:10 INFO - 'MOZ_NO_REMOTE': '1', 09:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:10 INFO - 'NO_EM_RESTART': '1', 09:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:10 INFO - 'PWD': '/builds/slave/test', 09:09:10 INFO - 'SHELL': '/bin/bash', 09:09:10 INFO - 'SHLVL': '1', 09:09:10 INFO - 'TERM': 'linux', 09:09:10 INFO - 'TMOUT': '86400', 09:09:10 INFO - 'USER': 'cltbld', 09:09:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:10 INFO - '_': '/tools/buildbot/bin/python'} 09:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:10 INFO - Running setup.py (path:/tmp/pip-GjYEW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:10 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)) 09:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:10 INFO - Running setup.py (path:/tmp/pip-qVpDmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:11 INFO - Running setup.py (path:/tmp/pip-l1qd7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:11 INFO - Running setup.py (path:/tmp/pip-HiyrZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:11 INFO - Running setup.py (path:/tmp/pip-apfIBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:11 INFO - Running setup.py (path:/tmp/pip-PvKRxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:11 INFO - Running setup.py (path:/tmp/pip-LW0KA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:11 INFO - Running setup.py (path:/tmp/pip-0ULcQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:11 INFO - Running setup.py (path:/tmp/pip-VEEabK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:11 INFO - Running setup.py (path:/tmp/pip-6QyOqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:11 INFO - Running setup.py (path:/tmp/pip-X9Y87e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:11 INFO - Running setup.py (path:/tmp/pip-zDRHNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:11 INFO - Running setup.py (path:/tmp/pip-nm1BKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:11 INFO - Running setup.py (path:/tmp/pip-M9k6cw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:11 INFO - Running setup.py (path:/tmp/pip-IuWn89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:11 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)) 09:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:11 INFO - Running setup.py (path:/tmp/pip-bmIzYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:12 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)) 09:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:12 INFO - Running setup.py (path:/tmp/pip-0wkgpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:12 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)) 09:09:12 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)) 09:09:12 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)) 09:09:12 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)) 09:09:12 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)) 09:09:12 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)) 09:09:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:14 INFO - Downloading blessings-1.6.tar.gz 09:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:09:14 INFO - Installing collected packages: blessings 09:09:14 INFO - Running setup.py install for blessings 09:09:14 INFO - Successfully installed blessings 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:09:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:14 INFO - Reading from file tmpfile_stdout 09:09:14 INFO - Current package versions: 09:09:14 INFO - argparse == 1.2.1 09:09:14 INFO - blessings == 1.6 09:09:14 INFO - blobuploader == 1.2.4 09:09:14 INFO - docopt == 0.6.1 09:09:14 INFO - functools32 == 3.2.3-2 09:09:14 INFO - jsonschema == 2.5.1 09:09:14 INFO - manifestparser == 1.1 09:09:14 INFO - mozInstall == 1.12 09:09:14 INFO - mozcrash == 0.17 09:09:14 INFO - mozdebug == 0.1 09:09:14 INFO - mozdevice == 0.48 09:09:14 INFO - mozfile == 1.2 09:09:14 INFO - mozhttpd == 0.7 09:09:14 INFO - mozinfo == 0.9 09:09:14 INFO - mozleak == 0.1 09:09:14 INFO - mozlog == 3.2 09:09:14 INFO - moznetwork == 0.27 09:09:14 INFO - mozprocess == 0.23 09:09:14 INFO - mozprofile == 0.28 09:09:14 INFO - mozrunner == 6.12 09:09:14 INFO - mozscreenshot == 0.1 09:09:14 INFO - mozsystemmonitor == 0.3 09:09:14 INFO - moztest == 0.7 09:09:14 INFO - mozversion == 1.4 09:09:14 INFO - psutil == 3.1.1 09:09:14 INFO - requests == 1.2.3 09:09:14 INFO - wsgiref == 0.1.2 09:09:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:14 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:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:14 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 09:09:14 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 09:09:14 INFO - Using env: {'DISPLAY': ':0', 09:09:14 INFO - 'HOME': '/home/cltbld', 09:09:14 INFO - 'LANG': 'en_US.UTF-8', 09:09:14 INFO - 'LANGUAGE': 'en_US:en', 09:09:14 INFO - 'LOGNAME': 'cltbld', 09:09:14 INFO - 'MAIL': '/var/mail/cltbld', 09:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:14 INFO - 'MOZ_NO_REMOTE': '1', 09:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:14 INFO - 'NO_EM_RESTART': '1', 09:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:14 INFO - 'PWD': '/builds/slave/test', 09:09:14 INFO - 'SHELL': '/bin/bash', 09:09:14 INFO - 'SHLVL': '1', 09:09:14 INFO - 'TERM': 'linux', 09:09:14 INFO - 'TMOUT': '86400', 09:09:14 INFO - 'USER': 'cltbld', 09:09:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:14 INFO - '_': '/tools/buildbot/bin/python'} 09:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 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)) 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:09:14 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:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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 0x296c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, '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': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', '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:09:14 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 09:09:14 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 09:09:14 INFO - Using env: {'DISPLAY': ':0', 09:09:14 INFO - 'HOME': '/home/cltbld', 09:09:14 INFO - 'LANG': 'en_US.UTF-8', 09:09:14 INFO - 'LANGUAGE': 'en_US:en', 09:09:14 INFO - 'LOGNAME': 'cltbld', 09:09:14 INFO - 'MAIL': '/var/mail/cltbld', 09:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:14 INFO - 'MOZ_NO_REMOTE': '1', 09:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:14 INFO - 'NO_EM_RESTART': '1', 09:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:14 INFO - 'PWD': '/builds/slave/test', 09:09:14 INFO - 'SHELL': '/bin/bash', 09:09:14 INFO - 'SHLVL': '1', 09:09:14 INFO - 'TERM': 'linux', 09:09:14 INFO - 'TMOUT': '86400', 09:09:14 INFO - 'USER': 'cltbld', 09:09:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:14 INFO - '_': '/tools/buildbot/bin/python'} 09:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Running post-action listener: _resource_record_post_action 09:09:14 INFO - Running post-action listener: _start_resource_monitoring 09:09:14 INFO - Starting resource monitoring. 09:09:14 INFO - ##### 09:09:14 INFO - ##### Finished create-virtualenv step (success) 09:09:14 INFO - ##### 09:09:14 INFO - ##### 09:09:14 INFO - ##### Running install step. 09:09:14 INFO - ##### 09:09:14 INFO - Running pre-action listener: _resource_record_pre_action 09:09:14 INFO - Running main action method: install 09:09:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:15 INFO - Reading from file tmpfile_stdout 09:09:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:09:15 INFO - Reading from file tmpfile_stdout 09:09:15 INFO - Output received: 09:09:15 INFO - Usage: mozinstall [options] installer 09:09:15 INFO - Options: 09:09:15 INFO - -h, --help show this help message and exit 09:09:15 INFO - -d DEST, --destination=DEST 09:09:15 INFO - Directory to install application into. [default: 09:09:15 INFO - "/builds/slave/test"] 09:09:15 INFO - --app=APP Application being installed. [default: firefox] 09:09:15 INFO - mkdir: /builds/slave/test/build/application 09:09:15 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'] 09:09:15 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 09:09:31 INFO - Reading from file tmpfile_stdout 09:09:31 INFO - Output received: 09:09:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:09:31 INFO - Running post-action listener: _resource_record_post_action 09:09:31 INFO - ##### 09:09:31 INFO - ##### Finished install step (success) 09:09:31 INFO - ##### 09:09:31 INFO - ##### 09:09:31 INFO - ##### Running run-tests step. 09:09:31 INFO - ##### 09:09:31 INFO - Running pre-action listener: _resource_record_pre_action 09:09:31 INFO - Running main action method: run_tests 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:31 INFO - Python 2.7.3 09:09:31 INFO - Return code: 0 09:09:31 INFO - grabbing minidump binary from tooltool 09:09:31 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:09:31 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 0x2c2b3b0>, '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 0x2c2c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:31 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 09:09:31 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 09:09:31 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 09:09:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:31 INFO - Return code: 0 09:09:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:09:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:09:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 09:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 09:09:31 INFO - Using env: {'DISPLAY': ':0', 09:09:31 INFO - 'HOME': '/home/cltbld', 09:09:31 INFO - 'LANG': 'en_US.UTF-8', 09:09:31 INFO - 'LANGUAGE': 'en_US:en', 09:09:31 INFO - 'LOGNAME': 'cltbld', 09:09:31 INFO - 'MAIL': '/var/mail/cltbld', 09:09:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:31 INFO - 'MOZ_NO_REMOTE': '1', 09:09:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:31 INFO - 'NO_EM_RESTART': '1', 09:09:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:31 INFO - 'PWD': '/builds/slave/test', 09:09:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:31 INFO - 'SHELL': '/bin/bash', 09:09:31 INFO - 'SHLVL': '1', 09:09:31 INFO - 'TERM': 'linux', 09:09:31 INFO - 'TMOUT': '86400', 09:09:31 INFO - 'USER': 'cltbld', 09:09:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950', 09:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:31 INFO - '_': '/tools/buildbot/bin/python'} 09:09:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 09:09:31 INFO - mozversion application_buildid: 20160714075544 09:09:31 INFO - mozversion application_changeset: d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a 09:09:31 INFO - mozversion application_display_name: Nightly 09:09:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:31 INFO - mozversion application_name: Firefox 09:09:31 INFO - mozversion application_remotingname: firefox 09:09:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:09:31 INFO - mozversion application_vendor: Mozilla 09:09:31 INFO - mozversion application_version: 50.0a1 09:09:31 INFO - mozversion platform_buildid: 20160714075544 09:09:31 INFO - mozversion platform_changeset: d5cf90154e6710b1fcb748c9f934bbdf9dc93a0a 09:09:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:09:31 INFO - mozversion platform_version: 50.0a1 09:09:31 INFO - using testdate: 1468512571 09:09:31 INFO - actual date: 1468512571 09:09:31 INFO - starting webserver on 'localhost:40267' 09:09:31 INFO - SUITE-START | Running 2 tests 09:09:31 INFO - TEST-START | dromaeo_css 09:09:31 INFO - operating with platform_type : linux_ 09:09:31 INFO - Initialising browser for dromaeo_css test... 09:09:31 INFO - TEST-INFO | started process 28990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT0J_lV/profile http://localhost:40267/getInfo.html) 09:09:36 INFO - PROCESS | 28990 | __metrics Screen width/height:1600/1200 09:09:36 INFO - PROCESS | 28990 | colorDepth:24 09:09:36 INFO - PROCESS | 28990 | Browser inner width/height: 1024/697 09:09:36 INFO - PROCESS | 28990 | __metrics 09:09:37 INFO - PROCESS | 28990 | 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" 09:09:37 INFO - PROCESS | 28990 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:38 INFO - TEST-INFO | 28990: exit 0 09:09:38 INFO - Browser initialized. 09:09:38 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:38 INFO - TEST-INFO | started process 29070 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT0J_lV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:50 INFO - PROCESS | 29070 | RSS: Main: 158883840 09:09:50 INFO - PROCESS | 29070 | 09:10:41 INFO - PROCESS | 29070 | 1468512641761 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] 09:12:53 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40267/tests/dromaeo/cssquery-ext.html) 09:12:54 INFO - PROCESS | 29070 | RSS: Main: 261267456 09:12:54 INFO - PROCESS | 29070 | 09:15:57 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-ext.html (next: http://localhost:40267/tests/dromaeo/cssquery-jquery.html) 09:15:58 INFO - PROCESS | 29070 | RSS: Main: 243957760 09:15:58 INFO - PROCESS | 29070 | 09:19:00 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40267/tests/dromaeo/cssquery-mootools.html) 09:19:01 INFO - PROCESS | 29070 | RSS: Main: 242085888 09:19:01 INFO - PROCESS | 29070 | 09:22:03 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40267/tests/dromaeo/cssquery-prototype.html) 09:22:04 INFO - PROCESS | 29070 | RSS: Main: 242663424 09:22:04 INFO - PROCESS | 29070 | 09:25:07 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40267/tests/dromaeo/cssquery-yui.html) 09:25:07 INFO - PROCESS | 29070 | RSS: Main: 221339648 09:25:07 INFO - PROCESS | 29070 | 09:28:10 INFO - PROCESS | 29070 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-yui.html (next: http://localhost:40267/tests/dromaeo/cssquery-dojo.html) 09:28:11 INFO - PROCESS | 29070 | RSS: Main: 280588288 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | __start_tp_report 09:28:11 INFO - PROCESS | 29070 | _x_x_mozilla_page_load 09:28:11 INFO - PROCESS | 29070 | _x_x_mozilla_page_load_details 09:28:11 INFO - PROCESS | 29070 | |i|pagename|runs| 09:28:11 INFO - PROCESS | 29070 | |0;dojo.html;2612.529744645963;2614.150401119636;2633.8419694818313;2649.456861343425;2665.173796123202;4498;4506.797194126264;4625.306204069387;4669.229577119771;4694.999999999995;4610.124076425477;4615.492295847454;4616.3767891334655;4768.666193366465;4809.975950120253;4310.5042920064125;4318.028443600197;4375.868723938287;4443.24464840977;4458.308962110862;4346.349745331073;4361.127774445108;4423.491298500665;4426.313921342197;4457.064016556527;4417.779111044435;4481.0813783174535;4498.460184777831;4498.460184777831;4524.592786649201;4205.369194620801;4228.640565551932;4248.9150216995795;4253.787310634456;4456.799444024994;4285.078708077747;4292.12011537634;4293.957060429387;4303.526612072669;4326.242907491176;4733.5503127202865;4782.569568738831;4791.976040119812;4795.808167673289;4808.471068182497;4830.637702172351;4994.950050499485;5057.0897238497055;5059.114654935372;5082.720450375228;5097.7196254206;5137.255098010767;5144.588432925357;5145.434002259786;5153.845384638467;4773.06925149596;4799.904001920012;4804.8798780030775;4814.494478079821;4815.6388270879825;4944.975275123601;4985.601151907838;5032;5092.134337162691;5107.387113546398;4943.728094954812;4964.081644895706;4978.6017118630425;4985.750712464363;4986.401631804207;4987.501249875056;5012.022655582126;5046.066477701637;5050.570701490415;5050.722210278396;4849.393825771778;4907.214845624754;4969.155243608523;4991.301217829507;5005.6996580205305;5023.422306434807;5024;5026.723530205801;5029.321041659329;5076.873078173076;4222.218889505451;4224.640905523003;4229.111886503869;4249.46881639795;4280.59334363235;3590.784552926833;3610.6028336882923;3650.5071815305123;3671.2106897016733;3710.8330125144435;4342.131573685276;4345.8261669533495;4350.042990542076;4350.717203381833;4376.7811609419405;5000;5002.199648056294;5003.049420610072;5006.624503162205;5041.395032596112;4291.549387314287;4333.58830911063;4380.7152535085115;4381.802818873159;4400.735955842596;4485.573870482299;4497.572730590588;4500.369948207253;4505.234110201273;4549.886252843706;4574.496805351475;4807.759612019315;4810.807567697253;4815.422149342101;4855.247436647325;4987.326710894128;5005.849824505125;5006.749662516932;5020.297158397754;5034.899302013866;4733.810647574196;4779.617630589614;4783.449903261169;4783.971446139097;4820.421549414093;4852.611791056636;4941.160002799605;4961.181405068268;4964.05682920244;5022.920072184498;5060.342155519759;5083.110455670317;5114.207297868837;5116.641835071509;5154.871128221825;4947.975260123676;4964.602831773522;4968.329275547755;4978.701277923336;5019.372578427697;4794.688345257547;4938.456769755252;4970.428400733817;4972.006933513942;4993.825216117419;4911.631627628014;4914.066327397783;4927.088488629615;4947.678400903929;4962.379702537183;4981.427135879276;5000.699958002531;5001.0748011616515;5001.749912504433;5060.114479965918;4701.670883038291;4705.082508910729;4719.457262414866;4731.881702957454;4884.535969082932 09:28:11 INFO - PROCESS | 29070 | |1;ext.html;577.950874175695;588.3293045927643;592.6177615438041;594.0465552787773;594.0762114911311;27010.86494567527;39007.60992390075;40004.79997600012;40253.798731006376;44854;40744.18511629765;41141.97145071375;41146.79426602863;41149.38275925856;41250.58749412505;7305.744298949538;7688.231176882309;7862.842743145134;7871.606419679022;7874.0157480314965;9810.607575696964;10087.293889427743;10101.040401161878;10422.687319380431;10436.217283703714;10145.644902428416;10222.539985700625;10229.283950123494;10239.078482936535;10258.948705256464;7601.31588157063;9767.755806104833;10023.248256380764;10072.294939354248;10092.697219083402;10004.599816007423;10031.699049028468;10072.395656260647;10072.496375181212;10076.798464030679;38673;38935.026624334336;38986.41520377197;39000.80499597512;39009.21981560353;3028.5457181422744;3106.254498920243;3153.1486498645468;3292.720118789906;3548.9112772180642;29361.412771744446;29621.25946851346;30029;30129.849350753324;30436.695633043608;3006.3085490337126;3018.818870867733;3020.6677265500775;3021.728044475986;3023.6069310989433;265533;266299;266465.66767166235;266599;266674;31521.36957260842;52205.47794522006;52526;52631.73684131555;52690.47309526856;31722.524162137586;33207.501887471706;33219.66780332214;33258.3348333032;33327.16682082919;13555.254461004739;13642.931785341209;13710.24593647339;13711.314434278247;13717.657058573615;10018.3488073275;10416.739581510523;10425.9478702606;10687;11585.304881706956;7678.808029799188;7755.1469338372735;7758.728444504528;7912.723054693058;7923.841523169504;3011.48804703201;3047.6342838859478;3051.9470782579974;3080.537919312076;3108.8445577721022;785.5679376343103;839.131498898637;844.5481667307961;857.661223816589;922.3866129024258;27210.45579088447;27373.86313068462;27673.584896226574;27938;27969;948.4688574398359;954.3319676226669;998.3161534348849;1014.2393205096178;1079.1097344690504;14357.928210358881;14807.851921480862;14973.925130374497;15001.849981500045;15028.156086427893;380440;380649;381965;382866;382955;48998.26502602464;49388.75305623449;49504.25743613848;49561;49592.75203623959;1100.4332768624165;1175.8765329640607;1209.9455524501418;1223.3149436315691;1236.1285293867802;27878.30304242329;29123.271918201368;29139.12582622525;29210.269743256587;29660.55509167321;42216.155676886905;42334.78832605817;42846.35730463983;42850;42866.78566607147;247835;248618;249006;249249;250189;22185.112595496645;22902.198423055113;24983.625245620966;25830.483390332465;25847.483050339262;42126.36810447784;42212.36681449842;42333.36499952565;42508.5749142517;43263.351049734905;35721.285574288886;36026;36035.099122522144;36207.63792362042;36218.818905905304;36079.27841443209;36267.45598815967;36482.45276320804;36532.269354613294;36952;35194;35267.647323526435;35327.544597318556;35379.6389343012;35833;32186.678133219306;32667.019989400356;33550.83224583862;33571.32857342888;33720.662793372736;24784.25647230586;25034.24897253085;25076.247712568653;25424.745752542236;25533 09:28:11 INFO - PROCESS | 29070 | |2;jquery.html;348.13488481124415;358.8223829204544;363.1792149741584;375.69568649393995;385.2045525988835;12808.10343275971;16141.43504977326;16858.83141168588;18482.44552663419;18493.890366578016;17407;17707.1146442678;17758.378456754017;17820.821791782077;18098.09509524525;4898.436679781827;5273.472652734734;5274.973625131869;5275.419703832576;5281.471852814726;3675.3384390809642;5120.871978200537;5742.511886489653;6061.66660833654;6097.939020609803;6148.200733904586;6148.415900489469;6168.4448399644025;6173.506119510451;6173.722182501776;5182.637215394924;5464.726763661841;5465.699386533759;5546.084895992157;5548.44515548446;3368.360011597813;5068.341115654978;5297.761600727938;5351.545118664918;5366.490183432567;13072.215667060007;13212.86787132126;13306.933465332611;17758.55603609907;21762;1801.5676237702987;1857.5634725839418;1859.3585213101458;1893.715942608606;1955.599102184049;8424.831503369898;8897.555122243863;9058.32062595308;9122.908770912272;9441.433513989183;1734.7744793176932;1805.638872225547;1806.8735188536807;1823.5988082621802;1832.8075552066973;66430;69710;71452.92820607747;73335;75135;8812.427192232484;9376.32774659219;10190.490475476197;10287.845682314772;10433.634822781167;12514.3742812859;13315.067945243934;13320.267385293708;13812.309384530934;14028.789568156486;6893.827654308682;7520.699172033167;7545.396368290523;7566.735164269224;7673.616319184019;7156.60638664868;7183.5330703504105;7292.45306602007;7316.6341682915645;7319.268073192638;4336.501302350207;4405.823767049346;4542.11428771393;4625.838095666635;4638.884027899329;1750.3348727483474;1782.3494424534952;1815.4462888818773;1816.7093265077788;1818.2908665620419;807.6809660184194;821.5112008355019;845.2899564365839;870.5081628879663;903.263839970422;16661.750073748663;16811.57971050709;17385.391511297043;17724.556886077695;17748.29006839712;844.7719115838689;858.1161403754144;863.2834747159842;904.895936967244;909.6361455417754;9438.150566449052;9546.236301095896;9960.203183745283;10033.498325083716;10064.798704026025;395223;395953;405199;405813;408084;84255;84920.57539712386;86241.56879215689;87749.56125219334;87905.56047219723;864.312871267344;872.7250915961451;879.2438502887512;883.1698203688512;900.4912224593215;15340.30968606393;15660.216989150726;15710.214489275262;15716;16014.759778603096;24879.62680559829;34586.48120278248;38257.80871095738;40007;41420.964475888344;51274.23088653748;54233.72883135559;55020.174697378774;55076;55174;7363.447741419308;7547.05661792276;7548.320651141424;7792.922070779219;7805.32185580103;11187.216894817284;11204.439778010903;11344.602938897098;11374.601888933848;11515.712107197052;9737.80524389494;9942.56031167286;9972.252081093802;10027.699169024942;10336.431496267476;10215.7446063849;10221.846672300071;10261.640842570474;10267.486625668538;10427.94786026065;6955.339242771928;7199.640017998974;7483.962580187064;7617.885731714139;9854.950725246328;9865.556049977911;11080;11263.605773797877;11320.830187547359;11335.603253886075;10382.792344153226;10607.94696026515;10678.786424271315;10916.945415272872;11160.888391115985 09:28:11 INFO - PROCESS | 29070 | |3;mootools.html;1390.687095403534;1443.732909411759;1512.7579587266039;1694.7034269002922;1715.957101072473;6937.791866244008;7142.071530701015;7712.15166331703;8269.25576698097;8274.669013239463;7520.586367749772;7782.494137881034;7788.415868809848;7799.142094369629;7953.880691789628;2149.7957694019083;2209.569134018867;2222.2666520048424;2224;2225.9220927267547;1889.2443022790924;2113.0913707105938;2176;2181.3674034529968;2185.0822654485155;2232.151782322715;2236.7763223677666;2241.742199647037;2242.6523888797265;2244.932652020434;2117.544727883505;2156.7196264485597;2156.8705877647235;2157.223399576151;2158.892055397224;2159.762426133124;2160.654295312758;2169.6853956176424;2185.169635538501;2186.497105665706;7976.122626511079;8032;8040;8041.959790201012;8051.194880511961;4498.977505112453;4613.331066995267;4618.630509559227;4628.976855115703;4690.7654617269;2774.833509989407;2807.410443806803;2857.2999615094227;2895.7683385329124;2904.8111872728205;886.2245535156737;892.3441270666056;909.7589138878203;913.9086091390874;916.6333466613341;10781.514831832586;11062.336259824437;11063.668089957315;11064.889351106547;11079.50142243601;4240.5123410808155;4243.6605071594195;4380.7152535085115;4411.911761764747;4434.423524941737;9458.716238512718;9470.57382417779;9471.621135154517;9478.857817132748;9482.478463684563;4656.4412270527755;4659.2312268476;4665.113628410591;4665.580097791215;4671.392718946515;3584.8386822592524;4146.398772178018;4173.08192197716;4175.561566035522;4177.352510360899;3673.1368128489785;3777.735558510878;3855.0747820522874;3863.5750067492554;3908.394198899175;1220.1154163231586;1240.2248594628356;1241.1312081542956;1245.302630526908;1256.214865708932;811.9431639785277;826.8635675113659;832.2800777327689;844.4046946902419;851.3614788908319;5162.9741851291255;5220.451852555503;5256.632035757537;5338.439463856317;5561.63849349791;818.2308629887887;863.2101627011215;879.5294517433142;886.3529623374973;886.5788750343664;3527.1358517162944;3533.5406397168204;3574.195805943642;3589.9102522436624;3641.417373220272;9697.418154910727;9957.950210248902;9975.900240997496;9990.050945160197;10002.149817265468;9216.170544651013;9625.422474651543;9628.037196280457;9939.155171810333;9944.204463642891;7376.668049937765;7415.1472580652835;7507.887381689388;7509.249075092447;7560.281773231534;5520.861978450571;5529.585281104014;5547.722613869371;5736.71316434185;5849.210356601804;9198.632054717737;9229.723108306762;9316.254699624149;9324.927633322255;9333.626654933727;8761.956190219009;9150;9157.725268241964;9311.76720581991;9468.195203407788;6247.843803904939;6758.594484330956;6844.076049733421;6972.09362782835;6981.825454363681;7671.616419178907;7979.401544884041;7980.760577182694;7986.800329991796;7992.040955085427;6762.695678694372;6857.862842743217;6965.303469652994;6972.372486476242;7301.707931682673;7062.258462861326;7073.71705131789;7099.787006389816;7185.353318201387;7192.2088570258275;6019.247594050744;6250.249970003629;6261.279952805486;6454.419102280817;6541.771038013647;4743.573078422958;4806.351142595705;4828.324034635224;4872.537108974641;4907.214845624683;4506.684532082722;4557.680962332605;4560.703554268962;4682.2508398656055;4684.203685373563 09:28:11 INFO - PROCESS | 29070 | |4;prototype.html;300.71432139467504;305.625608629429;309.28863613688526;309.7939869986459;310.1687477560138;4882.658213925027;4886.507880980699;4906.288588154715;4917.631177661677;4927.778249978751;4821.541953514419;4855.514448555143;4856.490068542805;4860.538248866361;4871.415430148379;3624.8368823402943;3732.4774531565467;3740.2706472237924;3826.8086595670243;3830.923381532368;3779.8677046303383;3804.7146464015173;3822.5986271441516;3825.6939444844347;3837.2709185254853;3599.1002249437643;3793.924121517568;3796.443458182146;3813.2945405100145;3816.694664426839;3691.280200360917;3806.1626442182674;3808.923821523554;3817.6564109230026;3819.8472061117523;3389.0510657016075;3400.0989737719524;3407.778494397881;3409.16475463513;3411.1301618087477;4728.895641439561;4736.431628204603;4736.550027747393;4739.3933336664695;4748.169070412699;4273.87178384649;4314.331278651814;4332.696711230215;4355.80398882051;4369.191699535564;4961.231009193581;5000.499950004971;5009.674371165899;5014.52362025607;5017.724025178613;3682.705383569335;3702.5927148013698;3704.703084087856;3710.6474884885893;3726.1988672435555;5102.744862756885;5241.31862857826;5246.23929530216;5254.6847189168775;5270.367555893279;3753.7560058596105;3778.074371778877;3788.431735239736;3788.6969042476535;3814.7138964577766;3831;3852.4799152114106;3876.8449262029203;3879.1853710721066;3883.10688541637;4354;4355.5208927018;4356.586124318248;4364.410804541346;4364.607185353333;4871.244957031666;5015.222640490657;5022.59819214462;5029.974850125726;5052.8484145475695;1303.1659737767836;1330.1909403211625;1330.3082397153426;1332.3005422153446;1341.9396127174105;879.5162660536679;881.7354793561906;910.517425764346;911.3165126155384;911.9726408207764;4342.652587792969;4372.982641977026;4486.977565112219;4492.460904691458;4526;5166.276721259027;5173.456787037382;5197.376314842243;5199.7140157291715;5251;4323.3947247385395;4505.977470112694;4529.456465224194;4530.116627257653;4622.445306563234;2730.290124567587;2740.8629568521496;2766.2531116598807;2775.653043369579;2780.7219278072434;4975.726335051607;4992.5756310714;4993.6005119590345;5012.8496145115705;5019.749012549358;5117.488251174853;5335.519803217651;5356.4107948125675;5393.1370980642905;5402.729863506887;4953.2570114483115;4960.975195124001;4965.3048573199785;5001.4998500149695;5002.499750025041;5163.199704045879;5184.377874654915;5209.775702709784;5230.241614965885;5237.685738855681;4836;5143.6399452037995;5188.9740551297;5194.7922083116255;5195.3246078009615;4938.827141050046;4947.901041979212;4949.133901567283;4957.529034741694;5018.347614810052;3721.330160571015;3728.366177749735;3754.1740817021305;3758.6241375862196;3760.6615404613717;5099.311592935054;5242.239875217996;5248.448912864094;5255.684658920477;5267.394249661338;5075.28945947575;5082.542571168613;5088.211327244283;5091;5100.617453690914;5036.420811606712;5078.4667609900425;5080.49195080489;5087.160618497906;5140.691558506502;4801.5438533339275;4809.2305231162845;4969.726665033458;4993.526909561615;5009.123403404463;4940.036692844861;5104.464031276662;5128.922646412123;5136.434992150786;5143.92284115746;4961.355023846877;4991.850244492671;4993.875153121201;5001.549860512426;5003.224500202475 09:28:11 INFO - PROCESS | 29070 | |5;yui.html;631.4853394483496;649.2792999770254;700.3066963705929;712.4941291682904;764.8967389402433;12321.507139714411;12332.691682707937;12385.194962327443;12454;12490.12569120162;13767.518136865212;14496.710065798678;14507.92746036271;14900.254987250648;14905.254737263147;623.3735096228285;774.2180397798209;794.0233512779271;811.2982270336175;815.8735396013598;798.0901771979948;807.1524898856206;807.5679511461369;808.3613944983456;809.0776514773163;843.0726201178685;849.9490030598153;851.1956201389689;852.0925214646381;852.6674596907211;644.4135836388887;802.7752229375726;833.9992009588458;838.8825564420986;839.5424493650944;618.6813790897692;621.7295476467751;622.0389498225207;622.0917460507665;622.7540121651967;13827.585172444842;13897.235652039133;13912.51306204278;13922.93038534811;13936.721265574632;13115.934420327838;13926.93036534811;14038.859611403857;14111.506097286649;14155.504557340444;10831.404272764994;10841.376426726127;10889.618863339745;10897.275681079731;11067.833982490269;3315.1712071982006;3339.248669049469;3381.644927282649;3387.220939183977;3452.9309413811834;63179;63768.681156594386;63781.681091594706;64786.67606661984;64972;10301.536430860479;10563.841542376871;10670.786584268271;10854.185936054828;11015.83476247857;20238.49403764888;20543.8972805138;20616.278430254868;20644.69032964507;20645.070971806304;6764.966175169093;6845.589264644137;6969;7078.433725302067;7107.857842843217;3731.029932217589;3752.5309336332957;3803.3344164771056;3804.1821008482757;3905.9804700976315;2858.4140251248446;2949.2921698792134;2982.537706655472;2984.0301901881976;2985.358147998191;479.8032806549298;491.2680106641079;497.2367416016397;498.0288437546798;505.375860811897;450.4122120632606;456.2859125468631;465.30669302738676;481.1675800864524;562.7355143082744;7881.527108373516;8084.83830323402;8106.392020598479;8120.269175774209;8193.95903020493;435.5296280017573;473.02084684702334;507.30115784029346;548.1257394455837;550.2901257378018;3681.813010985121;3832.405977073558;3841.250956063541;3895.9610403896163;3896.532416110085;73183.2681673187;85957.57021214854;90712.54643726739;94282.0571794287;97308.51345743226;81404.59297703592;82378;84840;85011;85472.14527854642;45185.54814451879;70669;72710.63644681743;74226;74530;7744.6902123914415;7988.360931125612;8055.395845311742;8200.302974247017;8335.708250211213;44535.331970019826;44933.775331123135;45361.546384537054;45563.772181138884;47305.526944731486;57342.42657573538;62802;63228.05157922543;63281;63721.68139159275;11425.31448113116;11507.884921151017;11640.88359116398;11707.531698731955;12217.77822217771;12698.746025079632;12941.741165176456;13013.609591712264;13102.737945241233;13304.733905322033;11697.473613687234;11767.529298828293;12056.518972237775;12134.33261170644;12389.62831115068;11609.941950290195;12135.575254866037;12142.08934329911;12532.122751407314;12643.114981951176;11790.23363481371;12022.458989345676;12026.398680066137;12054.15620906528;12799.104062715893;9330.486823224517;10284.794304113726;10653.520591573242;11026.283291586022;11112.277701949348;9854.901450985684;10092.747681308027;10247.897521024992;10373.429461379395;10390.272680912263 09:28:11 INFO - PROCESS | 29070 | __end_tp_report 09:28:11 INFO - PROCESS | 29070 | __start_cc_report 09:28:11 INFO - PROCESS | 29070 | _x_x_mozilla_cycle_collect,1018 09:28:11 INFO - PROCESS | 29070 | __end_cc_report 09:28:11 INFO - PROCESS | 29070 | __startTimestamp1468513691012__endTimestamp 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | ------- Summary: start ------- 09:28:11 INFO - PROCESS | 29070 | Number of tests: 6 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#0] dojo.html Cycles:165 Average:4661.79 Median:4808.47 stddev:492.44 (10.2%) stddev-sans-first:466.97 09:28:11 INFO - PROCESS | 29070 | Values: 2612.5 2614.2 2633.8 2649.5 2665.2 4498.0 4506.8 4625.3 4669.2 4695.0 4610.1 4615.5 4616.4 4768.7 4810.0 4310.5 4318.0 4375.9 4443.2 4458.3 4346.3 4361.1 4423.5 4426.3 4457.1 4417.8 4481.1 4498.5 4498.5 4524.6 4205.4 4228.6 4248.9 4253.8 4456.8 4285.1 4292.1 4294.0 4303.5 4326.2 4733.6 4782.6 4792.0 4795.8 4808.5 4830.6 4995.0 5057.1 5059.1 5082.7 5097.7 5137.3 5144.6 5145.4 5153.8 4773.1 4799.9 4804.9 4814.5 4815.6 4945.0 4985.6 5032.0 5092.1 5107.4 4943.7 4964.1 4978.6 4985.8 4986.4 4987.5 5012.0 5046.1 5050.6 5050.7 4849.4 4907.2 4969.2 4991.3 5005.7 5023.4 5024.0 5026.7 5029.3 5076.9 4222.2 4224.6 4229.1 4249.5 4280.6 3590.8 3610.6 3650.5 3671.2 3710.8 4342.1 4345.8 4350.0 4350.7 4376.8 5000.0 5002.2 5003.0 5006.6 5041.4 4291.5 4333.6 4380.7 4381.8 4400.7 4485.6 4497.6 4500.4 4505.2 4549.9 4574.5 4807.8 4810.8 4815.4 4855.2 4987.3 5005.8 5006.7 5020.3 5034.9 4733.8 4779.6 4783.4 4784.0 4820.4 4852.6 4941.2 4961.2 4964.1 5022.9 5060.3 5083.1 5114.2 5116.6 5154.9 4948.0 4964.6 4968.3 4978.7 5019.4 4794.7 4938.5 4970.4 4972.0 4993.8 4911.6 4914.1 4927.1 4947.7 4962.4 4981.4 5000.7 5001.1 5001.7 5060.1 4701.7 4705.1 4719.5 4731.9 4884.5 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#1] ext.html Cycles:180 Average:44881.66 Median:32426.85 stddev:80186.24 (247.3%) stddev-sans-first:80342.19 09:28:11 INFO - PROCESS | 29070 | Values: 578.0 588.3 592.6 594.0 594.1 27010.9 39007.6 40004.8 40253.8 44854.0 40744.2 41142.0 41146.8 41149.4 41250.6 7305.7 7688.2 7862.8 7871.6 7874.0 9810.6 10087.3 10101.0 10422.7 10436.2 10145.6 10222.5 10229.3 10239.1 10258.9 7601.3 9767.8 10023.2 10072.3 10092.7 10004.6 10031.7 10072.4 10072.5 10076.8 38673.0 38935.0 38986.4 39000.8 39009.2 3028.5 3106.3 3153.1 3292.7 3548.9 29361.4 29621.3 30029.0 30129.8 30436.7 3006.3 3018.8 3020.7 3021.7 3023.6 265533.0 266299.0 266465.7 266599.0 266674.0 31521.4 52205.5 52526.0 52631.7 52690.5 31722.5 33207.5 33219.7 33258.3 33327.2 13555.3 13642.9 13710.2 13711.3 13717.7 10018.3 10416.7 10425.9 10687.0 11585.3 7678.8 7755.1 7758.7 7912.7 7923.8 3011.5 3047.6 3051.9 3080.5 3108.8 785.6 839.1 844.5 857.7 922.4 27210.5 27373.9 27673.6 27938.0 27969.0 948.5 954.3 998.3 1014.2 1079.1 14357.9 14807.9 14973.9 15001.8 15028.2 380440.0 380649.0 381965.0 382866.0 382955.0 48998.3 49388.8 49504.3 49561.0 49592.8 1100.4 1175.9 1209.9 1223.3 1236.1 27878.3 29123.3 29139.1 29210.3 29660.6 42216.2 42334.8 42846.4 42850.0 42866.8 247835.0 248618.0 249006.0 249249.0 250189.0 22185.1 22902.2 24983.6 25830.5 25847.5 42126.4 42212.4 42333.4 42508.6 43263.4 35721.3 36026.0 36035.1 36207.6 36218.8 36079.3 36267.5 36482.5 36532.3 36952.0 35194.0 35267.6 35327.5 35379.6 35833.0 32186.7 32667.0 33550.8 33571.3 33720.7 24784.3 25034.2 25076.2 25424.7 25533.0 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#2] jquery.html Cycles:180 Average:24945.03 Median:4583.98 stddev:66618.07 (1453.3%) stddev-sans-first:66779.35 09:28:11 INFO - PROCESS | 29070 | Values: 348.1 358.8 363.2 375.7 385.2 12808.1 16141.4 16858.8 18482.4 18493.9 17407.0 17707.1 17758.4 17820.8 18098.1 4898.4 5273.5 5275.0 5275.4 5281.5 3675.3 5120.9 5742.5 6061.7 6097.9 6148.2 6148.4 6168.4 6173.5 6173.7 5182.6 5464.7 5465.7 5546.1 5548.4 3368.4 5068.3 5297.8 5351.5 5366.5 13072.2 13212.9 13306.9 17758.6 21762.0 1801.6 1857.6 1859.4 1893.7 1955.6 8424.8 8897.6 9058.3 9122.9 9441.4 1734.8 1805.6 1806.9 1823.6 1832.8 66430.0 69710.0 71452.9 73335.0 75135.0 8812.4 9376.3 10190.5 10287.8 10433.6 12514.4 13315.1 13320.3 13812.3 14028.8 6893.8 7520.7 7545.4 7566.7 7673.6 7156.6 7183.5 7292.5 7316.6 7319.3 4336.5 4405.8 4542.1 4625.8 4638.9 1750.3 1782.3 1815.4 1816.7 1818.3 807.7 821.5 845.3 870.5 903.3 16661.8 16811.6 17385.4 17724.6 17748.3 844.8 858.1 863.3 904.9 909.6 9438.2 9546.2 9960.2 10033.5 10064.8 395223.0 395953.0 405199.0 405813.0 408084.0 84255.0 84920.6 86241.6 87749.6 87905.6 864.3 872.7 879.2 883.2 900.5 15340.3 15660.2 15710.2 15716.0 16014.8 24879.6 34586.5 38257.8 40007.0 41421.0 51274.2 54233.7 55020.2 55076.0 55174.0 7363.4 7547.1 7548.3 7792.9 7805.3 11187.2 11204.4 11344.6 11374.6 11515.7 9737.8 9942.6 9972.3 10027.7 10336.4 10215.7 10221.8 10261.6 10267.5 10427.9 6955.3 7199.6 7484.0 7617.9 9855.0 9865.6 11080.0 11263.6 11320.8 11335.6 10382.8 10607.9 10678.8 10916.9 11160.9 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#3] mootools.html Cycles:180 Average:5252.83 Median:5525.22 stddev:2969.70 (53.7%) stddev-sans-first:2963.85 09:28:11 INFO - PROCESS | 29070 | Values: 1390.7 1443.7 1512.8 1694.7 1716.0 6937.8 7142.1 7712.2 8269.3 8274.7 7520.6 7782.5 7788.4 7799.1 7953.9 2149.8 2209.6 2222.3 2224.0 2225.9 1889.2 2113.1 2176.0 2181.4 2185.1 2232.2 2236.8 2241.7 2242.7 2244.9 2117.5 2156.7 2156.9 2157.2 2158.9 2159.8 2160.7 2169.7 2185.2 2186.5 7976.1 8032.0 8040.0 8042.0 8051.2 4499.0 4613.3 4618.6 4629.0 4690.8 2774.8 2807.4 2857.3 2895.8 2904.8 886.2 892.3 909.8 913.9 916.6 10781.5 11062.3 11063.7 11064.9 11079.5 4240.5 4243.7 4380.7 4411.9 4434.4 9458.7 9470.6 9471.6 9478.9 9482.5 4656.4 4659.2 4665.1 4665.6 4671.4 3584.8 4146.4 4173.1 4175.6 4177.4 3673.1 3777.7 3855.1 3863.6 3908.4 1220.1 1240.2 1241.1 1245.3 1256.2 811.9 826.9 832.3 844.4 851.4 5163.0 5220.5 5256.6 5338.4 5561.6 818.2 863.2 879.5 886.4 886.6 3527.1 3533.5 3574.2 3589.9 3641.4 9697.4 9958.0 9975.9 9990.1 10002.1 9216.2 9625.4 9628.0 9939.2 9944.2 7376.7 7415.1 7507.9 7509.2 7560.3 5520.9 5529.6 5547.7 5736.7 5849.2 9198.6 9229.7 9316.3 9324.9 9333.6 8762.0 9150.0 9157.7 9311.8 9468.2 6247.8 6758.6 6844.1 6972.1 6981.8 7671.6 7979.4 7980.8 7986.8 7992.0 6762.7 6857.9 6965.3 6972.4 7301.7 7062.3 7073.7 7099.8 7185.4 7192.2 6019.2 6250.2 6261.3 6454.4 6541.8 4743.6 4806.4 4828.3 4872.5 4907.2 4506.7 4557.7 4560.7 4682.3 4684.2 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#4] prototype.html Cycles:180 Average:4234.19 Median:4858.51 stddev:1213.37 (25.0%) stddev-sans-first:1180.31 09:28:11 INFO - PROCESS | 29070 | Values: 300.7 305.6 309.3 309.8 310.2 4882.7 4886.5 4906.3 4917.6 4927.8 4821.5 4855.5 4856.5 4860.5 4871.4 3624.8 3732.5 3740.3 3826.8 3830.9 3779.9 3804.7 3822.6 3825.7 3837.3 3599.1 3793.9 3796.4 3813.3 3816.7 3691.3 3806.2 3808.9 3817.7 3819.8 3389.1 3400.1 3407.8 3409.2 3411.1 4728.9 4736.4 4736.6 4739.4 4748.2 4273.9 4314.3 4332.7 4355.8 4369.2 4961.2 5000.5 5009.7 5014.5 5017.7 3682.7 3702.6 3704.7 3710.6 3726.2 5102.7 5241.3 5246.2 5254.7 5270.4 3753.8 3778.1 3788.4 3788.7 3814.7 3831.0 3852.5 3876.8 3879.2 3883.1 4354.0 4355.5 4356.6 4364.4 4364.6 4871.2 5015.2 5022.6 5030.0 5052.8 1303.2 1330.2 1330.3 1332.3 1341.9 879.5 881.7 910.5 911.3 912.0 4342.7 4373.0 4487.0 4492.5 4526.0 5166.3 5173.5 5197.4 5199.7 5251.0 4323.4 4506.0 4529.5 4530.1 4622.4 2730.3 2740.9 2766.3 2775.7 2780.7 4975.7 4992.6 4993.6 5012.8 5019.7 5117.5 5335.5 5356.4 5393.1 5402.7 4953.3 4961.0 4965.3 5001.5 5002.5 5163.2 5184.4 5209.8 5230.2 5237.7 4836.0 5143.6 5189.0 5194.8 5195.3 4938.8 4947.9 4949.1 4957.5 5018.3 3721.3 3728.4 3754.2 3758.6 3760.7 5099.3 5242.2 5248.4 5255.7 5267.4 5075.3 5082.5 5088.2 5091.0 5100.6 5036.4 5078.5 5080.5 5087.2 5140.7 4801.5 4809.2 4969.7 4993.5 5009.1 4940.0 5104.5 5128.9 5136.4 5143.9 4961.4 4991.9 4993.9 5001.5 5003.2 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - PROCESS | 29070 | [#5] yui.html Cycles:180 Average:17580.42 Median:22692.09 stddev:24269.94 (107.0%) stddev-sans-first:24304.66 09:28:11 INFO - PROCESS | 29070 | Values: 631.5 649.3 700.3 712.5 764.9 12321.5 12332.7 12385.2 12454.0 12490.1 13767.5 14496.7 14507.9 14900.3 14905.3 623.4 774.2 794.0 811.3 815.9 798.1 807.2 807.6 808.4 809.1 843.1 849.9 851.2 852.1 852.7 644.4 802.8 834.0 838.9 839.5 618.7 621.7 622.0 622.1 622.8 13827.6 13897.2 13912.5 13922.9 13936.7 13115.9 13926.9 14038.9 14111.5 14155.5 10831.4 10841.4 10889.6 10897.3 11067.8 3315.2 3339.2 3381.6 3387.2 3452.9 63179.0 63768.7 63781.7 64786.7 64972.0 10301.5 10563.8 10670.8 10854.2 11015.8 20238.5 20543.9 20616.3 20644.7 20645.1 6765.0 6845.6 6969.0 7078.4 7107.9 3731.0 3752.5 3803.3 3804.2 3906.0 2858.4 2949.3 2982.5 2984.0 2985.4 479.8 491.3 497.2 498.0 505.4 450.4 456.3 465.3 481.2 562.7 7881.5 8084.8 8106.4 8120.3 8194.0 435.5 473.0 507.3 548.1 550.3 3681.8 3832.4 3841.3 3896.0 3896.5 73183.3 85957.6 90712.5 94282.1 97308.5 81404.6 82378.0 84840.0 85011.0 85472.1 45185.5 70669.0 72710.6 74226.0 74530.0 7744.7 7988.4 8055.4 8200.3 8335.7 44535.3 44933.8 45361.5 45563.8 47305.5 57342.4 62802.0 63228.1 63281.0 63721.7 11425.3 11507.9 11640.9 11707.5 12217.8 12698.7 12941.7 13013.6 13102.7 13304.7 11697.5 11767.5 12056.5 12134.3 12389.6 11609.9 12135.6 12142.1 12532.1 12643.1 11790.2 12022.5 12026.4 12054.2 12799.1 9330.5 10284.8 10653.5 11026.3 11112.3 9854.9 10092.7 10247.9 10373.4 10390.3 09:28:11 INFO - PROCESS | 29070 | -------- Summary: end -------- 09:28:11 INFO - PROCESS | 29070 | 09:28:11 INFO - TEST-INFO | 29070: exit 0 09:28:11 INFO - TEST-OK | dromaeo_css | took 1119920ms 09:28:11 INFO - TEST-START | kraken 09:28:11 INFO - operating with platform_type : linux_ 09:28:11 INFO - Initialising browser for kraken test... 09:28:11 INFO - TEST-INFO | started process 29257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5gkfm/profile http://localhost:40267/getInfo.html) 09:28:16 INFO - PROCESS | 29257 | __metrics Screen width/height:1600/1200 09:28:16 INFO - PROCESS | 29257 | colorDepth:24 09:28:16 INFO - PROCESS | 29257 | Browser inner width/height: 1024/697 09:28:16 INFO - PROCESS | 29257 | __metrics 09:28:17 INFO - PROCESS | 29257 | 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" 09:28:17 INFO - PROCESS | 29257 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:18 INFO - TEST-INFO | 29257: exit 0 09:28:18 INFO - Browser initialized. 09:28:18 INFO - Running cycle 1/1 for kraken test... 09:28:18 INFO - TEST-INFO | started process 29337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5gkfm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:29 INFO - PROCESS | 29337 | RSS: Main: 159117312 09:28:29 INFO - PROCESS | 29337 | 09:28:32 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:33 INFO - PROCESS | 29337 | RSS: Main: 217120768 09:28:33 INFO - PROCESS | 29337 | 09:28:36 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-dft) 09:28:36 INFO - PROCESS | 29337 | RSS: Main: 203759616 09:28:36 INFO - PROCESS | 29337 | 09:28:40 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-fft) 09:28:40 INFO - PROCESS | 29337 | RSS: Main: 271654912 09:28:40 INFO - PROCESS | 29337 | 09:28:43 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-oscillator) 09:28:43 INFO - PROCESS | 29337 | RSS: Main: 181350400 09:28:43 INFO - PROCESS | 29337 | 09:28:45 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:46 INFO - PROCESS | 29337 | RSS: Main: 186179584 09:28:46 INFO - PROCESS | 29337 | 09:28:49 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:50 INFO - PROCESS | 29337 | RSS: Main: 222314496 09:28:50 INFO - PROCESS | 29337 | 09:28:53 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:54 INFO - PROCESS | 29337 | RSS: Main: 218345472 09:28:54 INFO - PROCESS | 29337 | 09:28:57 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40267/tests/kraken/driver.html?testName=json-parse-financial) 09:28:57 INFO - PROCESS | 29337 | RSS: Main: 221065216 09:28:57 INFO - PROCESS | 29337 | 09:28:59 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:00 INFO - PROCESS | 29337 | RSS: Main: 206876672 09:29:00 INFO - PROCESS | 29337 | 09:29:01 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:02 INFO - PROCESS | 29337 | RSS: Main: 211464192 09:29:02 INFO - PROCESS | 29337 | 09:29:04 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:05 INFO - PROCESS | 29337 | RSS: Main: 210083840 09:29:05 INFO - PROCESS | 29337 | 09:29:08 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:08 INFO - PROCESS | 29337 | RSS: Main: 224509952 09:29:08 INFO - PROCESS | 29337 | 09:29:11 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:12 INFO - PROCESS | 29337 | RSS: Main: 225148928 09:29:12 INFO - PROCESS | 29337 | 09:29:13 INFO - PROCESS | 29337 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40267/tests/kraken/driver.html?testName=ai-astar) 09:29:14 INFO - PROCESS | 29337 | RSS: Main: 267116544 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | __start_tp_report 09:29:14 INFO - PROCESS | 29337 | _x_x_mozilla_page_load 09:29:14 INFO - PROCESS | 29337 | _x_x_mozilla_page_load_details 09:29:14 INFO - PROCESS | 29337 | |i|pagename|runs| 09:29:14 INFO - PROCESS | 29337 | |0;ai-astar;105;115;114;114;115;112;113;112;113;114 09:29:14 INFO - PROCESS | 29337 | |1;audio-beat-detection;155;129;125;175;125;124;149;125;162;123 09:29:14 INFO - PROCESS | 29337 | |2;audio-dft;161;162;161;161;162;161;164;162;161;163 09:29:14 INFO - PROCESS | 29337 | |3;audio-fft;76;75;76;116;78;76;74;75;108;76 09:29:14 INFO - PROCESS | 29337 | |4;audio-oscillator;86;86;111;85;84;85;114;84;86;125 09:29:14 INFO - PROCESS | 29337 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;116 09:29:14 INFO - PROCESS | 29337 | |6;imaging-darkroom;145;147;147;147;147;147;146;146;146;146 09:29:14 INFO - PROCESS | 29337 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 09:29:14 INFO - PROCESS | 29337 | |8;json-parse-financial;66;67;67;66;66;66;66;65;67;66 09:29:14 INFO - PROCESS | 29337 | |9;json-stringify-tinderbox;65;66;65;65;64;65;65;66;66;66 09:29:14 INFO - PROCESS | 29337 | |10;stanford-crypto-aes;79;79;78;78;78;78;82;79;79;78 09:29:14 INFO - PROCESS | 29337 | |11;stanford-crypto-ccm;136;125;126;128;127;128;127;129;219;138 09:29:14 INFO - PROCESS | 29337 | |12;stanford-crypto-pbkdf2;161;166;165;166;162;165;161;161;162;163 09:29:14 INFO - PROCESS | 29337 | |13;stanford-crypto-sha256-iterative;67;66;66;64;65;66;66;65;64;75 09:29:14 INFO - PROCESS | 29337 | __end_tp_report 09:29:14 INFO - PROCESS | 29337 | __start_cc_report 09:29:14 INFO - PROCESS | 29337 | _x_x_mozilla_cycle_collect,1949 09:29:14 INFO - PROCESS | 29337 | __end_cc_report 09:29:14 INFO - PROCESS | 29337 | __startTimestamp1468513754236__endTimestamp 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | ------- Summary: start ------- 09:29:14 INFO - PROCESS | 29337 | Number of tests: 14 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:2.91 (2.6%) stddev-sans-first:1.13 09:29:14 INFO - PROCESS | 29337 | Values: 105.0 115.0 114.0 114.0 115.0 112.0 113.0 112.0 113.0 114.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:139.00 stddev:19.29 (13.9%) stddev-sans-first:19.60 09:29:14 INFO - PROCESS | 29337 | Values: 155.0 129.0 125.0 175.0 125.0 124.0 149.0 125.0 162.0 123.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 09:29:14 INFO - PROCESS | 29337 | Values: 161.0 162.0 161.0 161.0 162.0 161.0 164.0 162.0 161.0 163.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.00 stddev:15.43 (20.3%) stddev-sans-first:16.16 09:29:14 INFO - PROCESS | 29337 | Values: 76.0 75.0 76.0 116.0 78.0 76.0 74.0 75.0 108.0 76.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:85.00 stddev:15.64 (18.4%) stddev-sans-first:16.27 09:29:14 INFO - PROCESS | 29337 | Values: 86.0 86.0 111.0 85.0 84.0 85.0 114.0 84.0 86.0 125.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:29:14 INFO - PROCESS | 29337 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.53 09:29:14 INFO - PROCESS | 29337 | Values: 145.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 146.0 146.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:29:14 INFO - PROCESS | 29337 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 09:29:14 INFO - PROCESS | 29337 | Values: 66.0 67.0 67.0 66.0 66.0 66.0 66.0 65.0 67.0 66.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:29:14 INFO - PROCESS | 29337 | Values: 65.0 66.0 65.0 65.0 64.0 65.0 65.0 66.0 66.0 66.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.00 stddev:1.23 (1.6%) stddev-sans-first:1.30 09:29:14 INFO - PROCESS | 29337 | Values: 79.0 79.0 78.0 78.0 78.0 78.0 82.0 79.0 79.0 78.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:128.50 stddev:28.67 (22.3%) stddev-sans-first:30.40 09:29:14 INFO - PROCESS | 29337 | Values: 136.0 125.0 126.0 128.0 127.0 128.0 127.0 129.0 219.0 138.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:2.10 (1.3%) stddev-sans-first:2.07 09:29:14 INFO - PROCESS | 29337 | Values: 161.0 166.0 165.0 166.0 162.0 165.0 161.0 161.0 162.0 163.0 09:29:14 INFO - PROCESS | 29337 | 09:29:14 INFO - PROCESS | 29337 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:3.17 (4.8%) stddev-sans-first:3.35 09:29:14 INFO - PROCESS | 29337 | Values: 67.0 66.0 66.0 64.0 65.0 66.0 66.0 65.0 64.0 75.0 09:29:14 INFO - PROCESS | 29337 | -------- Summary: end -------- 09:29:14 INFO - PROCESS | 29337 | 09:29:15 INFO - TEST-INFO | 29337: exit 0 09:29:15 INFO - TEST-OK | kraken | took 63499ms 09:29:15 INFO - SUITE-END | took 1183s 09:29:15 INFO - Completed test suite (00:19:43) 09:29:15 INFO - javascript benchmark 09:29:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2612.529744645963, 2614.150401119636, 2633.8419694818313, 2649.456861343425, 2665.173796123202, 4498.0, 4506.797194126264, 4625.306204069387, 4669.229577119771, 4694.999999999995, 4610.124076425477, 4615.492295847454, 4616.3767891334655, 4768.666193366465, 4809.975950120253, 4310.5042920064125, 4318.028443600197, 4375.868723938287, 4443.24464840977, 4458.308962110862, 4346.349745331073, 4361.127774445108, 4423.491298500665, 4426.313921342197, 4457.064016556527, 4417.779111044435, 4481.0813783174535, 4498.460184777831, 4498.460184777831, 4524.592786649201, 4205.369194620801, 4228.640565551932, 4248.9150216995795, 4253.787310634456, 4456.799444024994, 4285.078708077747, 4292.12011537634, 4293.957060429387, 4303.526612072669, 4326.242907491176, 4733.5503127202865, 4782.569568738831, 4791.976040119812, 4795.808167673289, 4808.471068182497, 4830.637702172351, 4994.950050499485, 5057.0897238497055, 5059.114654935372, 5082.720450375228, 5097.7196254206, 5137.255098010767, 5144.588432925357, 5145.434002259786, 5153.845384638467, 4773.06925149596, 4799.904001920012, 4804.8798780030775, 4814.494478079821, 4815.6388270879825, 4944.975275123601, 4985.601151907838, 5032.0, 5092.134337162691, 5107.387113546398, 4943.728094954812, 4964.081644895706, 4978.6017118630425, 4985.750712464363, 4986.401631804207, 4987.501249875056, 5012.022655582126, 5046.066477701637, 5050.570701490415, 5050.722210278396, 4849.393825771778, 4907.214845624754, 4969.155243608523, 4991.301217829507, 5005.6996580205305, 5023.422306434807, 5024.0, 5026.723530205801, 5029.321041659329, 5076.873078173076, 4222.218889505451, 4224.640905523003, 4229.111886503869, 4249.46881639795, 4280.59334363235, 3590.784552926833, 3610.6028336882923, 3650.5071815305123, 3671.2106897016733, 3710.8330125144435, 4342.131573685276, 4345.8261669533495, 4350.042990542076, 4350.717203381833, 4376.7811609419405, 5000.0, 5002.199648056294, 5003.049420610072, 5006.624503162205, 5041.395032596112, 4291.549387314287, 4333.58830911063, 4380.7152535085115, 4381.802818873159, 4400.735955842596, 4485.573870482299, 4497.572730590588, 4500.369948207253, 4505.234110201273, 4549.886252843706, 4574.496805351475, 4807.759612019315, 4810.807567697253, 4815.422149342101, 4855.247436647325, 4987.326710894128, 5005.849824505125, 5006.749662516932, 5020.297158397754, 5034.899302013866, 4733.810647574196, 4779.617630589614, 4783.449903261169, 4783.971446139097, 4820.421549414093, 4852.611791056636, 4941.160002799605, 4961.181405068268, 4964.05682920244, 5022.920072184498, 5060.342155519759, 5083.110455670317, 5114.207297868837, 5116.641835071509, 5154.871128221825, 4947.975260123676, 4964.602831773522, 4968.329275547755, 4978.701277923336, 5019.372578427697, 4794.688345257547, 4938.456769755252, 4970.428400733817, 4972.006933513942, 4993.825216117419, 4911.631627628014, 4914.066327397783, 4927.088488629615, 4947.678400903929, 4962.379702537183, 4981.427135879276, 5000.699958002531, 5001.0748011616515, 5001.749912504433, 5060.114479965918, 4701.670883038291, 4705.082508910729, 4719.457262414866, 4731.881702957454, 4884.535969082932], "value": 4630.062083403735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.950874175695, 588.3293045927643, 592.6177615438041, 594.0465552787773, 594.0762114911311, 27010.86494567527, 39007.60992390075, 40004.79997600012, 40253.798731006376, 44854.0, 40744.18511629765, 41141.97145071375, 41146.79426602863, 41149.38275925856, 41250.58749412505, 7305.744298949538, 7688.231176882309, 7862.842743145134, 7871.606419679022, 7874.0157480314965, 9810.607575696964, 10087.293889427743, 10101.040401161878, 10422.687319380431, 10436.217283703714, 10145.644902428416, 10222.539985700625, 10229.283950123494, 10239.078482936535, 10258.948705256464, 7601.31588157063, 9767.755806104833, 10023.248256380764, 10072.294939354248, 10092.697219083402, 10004.599816007423, 10031.699049028468, 10072.395656260647, 10072.496375181212, 10076.798464030679, 38673.0, 38935.026624334336, 38986.41520377197, 39000.80499597512, 39009.21981560353, 3028.5457181422744, 3106.254498920243, 3153.1486498645468, 3292.720118789906, 3548.9112772180642, 29361.412771744446, 29621.25946851346, 30029.0, 30129.849350753324, 30436.695633043608, 3006.3085490337126, 3018.818870867733, 3020.6677265500775, 3021.728044475986, 3023.6069310989433, 265533.0, 266299.0, 266465.66767166235, 266599.0, 266674.0, 31521.36957260842, 52205.47794522006, 52526.0, 52631.73684131555, 52690.47309526856, 31722.524162137586, 33207.501887471706, 33219.66780332214, 33258.3348333032, 33327.16682082919, 13555.254461004739, 13642.931785341209, 13710.24593647339, 13711.314434278247, 13717.657058573615, 10018.3488073275, 10416.739581510523, 10425.9478702606, 10687.0, 11585.304881706956, 7678.808029799188, 7755.1469338372735, 7758.728444504528, 7912.723054693058, 7923.841523169504, 3011.48804703201, 3047.6342838859478, 3051.9470782579974, 3080.537919312076, 3108.8445577721022, 785.5679376343103, 839.131498898637, 844.5481667307961, 857.661223816589, 922.3866129024258, 27210.45579088447, 27373.86313068462, 27673.584896226574, 27938.0, 27969.0, 948.4688574398359, 954.3319676226669, 998.3161534348849, 1014.2393205096178, 1079.1097344690504, 14357.928210358881, 14807.851921480862, 14973.925130374497, 15001.849981500045, 15028.156086427893, 380440.0, 380649.0, 381965.0, 382866.0, 382955.0, 48998.26502602464, 49388.75305623449, 49504.25743613848, 49561.0, 49592.75203623959, 1100.4332768624165, 1175.8765329640607, 1209.9455524501418, 1223.3149436315691, 1236.1285293867802, 27878.30304242329, 29123.271918201368, 29139.12582622525, 29210.269743256587, 29660.55509167321, 42216.155676886905, 42334.78832605817, 42846.35730463983, 42850.0, 42866.78566607147, 247835.0, 248618.0, 249006.0, 249249.0, 250189.0, 22185.112595496645, 22902.198423055113, 24983.625245620966, 25830.483390332465, 25847.483050339262, 42126.36810447784, 42212.36681449842, 42333.36499952565, 42508.5749142517, 43263.351049734905, 35721.285574288886, 36026.0, 36035.099122522144, 36207.63792362042, 36218.818905905304, 36079.27841443209, 36267.45598815967, 36482.45276320804, 36532.269354613294, 36952.0, 35194.0, 35267.647323526435, 35327.544597318556, 35379.6389343012, 35833.0, 32186.678133219306, 32667.019989400356, 33550.83224583862, 33571.32857342888, 33720.662793372736, 24784.25647230586, 25034.24897253085, 25076.247712568653, 25424.745752542236, 25533.0], "value": 16744.997259958476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.13488481124415, 358.8223829204544, 363.1792149741584, 375.69568649393995, 385.2045525988835, 12808.10343275971, 16141.43504977326, 16858.83141168588, 18482.44552663419, 18493.890366578016, 17407.0, 17707.1146442678, 17758.378456754017, 17820.821791782077, 18098.09509524525, 4898.436679781827, 5273.472652734734, 5274.973625131869, 5275.419703832576, 5281.471852814726, 3675.3384390809642, 5120.871978200537, 5742.511886489653, 6061.66660833654, 6097.939020609803, 6148.200733904586, 6148.415900489469, 6168.4448399644025, 6173.506119510451, 6173.722182501776, 5182.637215394924, 5464.726763661841, 5465.699386533759, 5546.084895992157, 5548.44515548446, 3368.360011597813, 5068.341115654978, 5297.761600727938, 5351.545118664918, 5366.490183432567, 13072.215667060007, 13212.86787132126, 13306.933465332611, 17758.55603609907, 21762.0, 1801.5676237702987, 1857.5634725839418, 1859.3585213101458, 1893.715942608606, 1955.599102184049, 8424.831503369898, 8897.555122243863, 9058.32062595308, 9122.908770912272, 9441.433513989183, 1734.7744793176932, 1805.638872225547, 1806.8735188536807, 1823.5988082621802, 1832.8075552066973, 66430.0, 69710.0, 71452.92820607747, 73335.0, 75135.0, 8812.427192232484, 9376.32774659219, 10190.490475476197, 10287.845682314772, 10433.634822781167, 12514.3742812859, 13315.067945243934, 13320.267385293708, 13812.309384530934, 14028.789568156486, 6893.827654308682, 7520.699172033167, 7545.396368290523, 7566.735164269224, 7673.616319184019, 7156.60638664868, 7183.5330703504105, 7292.45306602007, 7316.6341682915645, 7319.268073192638, 4336.501302350207, 4405.823767049346, 4542.11428771393, 4625.838095666635, 4638.884027899329, 1750.3348727483474, 1782.3494424534952, 1815.4462888818773, 1816.7093265077788, 1818.2908665620419, 807.6809660184194, 821.5112008355019, 845.2899564365839, 870.5081628879663, 903.263839970422, 16661.750073748663, 16811.57971050709, 17385.391511297043, 17724.556886077695, 17748.29006839712, 844.7719115838689, 858.1161403754144, 863.2834747159842, 904.895936967244, 909.6361455417754, 9438.150566449052, 9546.236301095896, 9960.203183745283, 10033.498325083716, 10064.798704026025, 395223.0, 395953.0, 405199.0, 405813.0, 408084.0, 84255.0, 84920.57539712386, 86241.56879215689, 87749.56125219334, 87905.56047219723, 864.312871267344, 872.7250915961451, 879.2438502887512, 883.1698203688512, 900.4912224593215, 15340.30968606393, 15660.216989150726, 15710.214489275262, 15716.0, 16014.759778603096, 24879.62680559829, 34586.48120278248, 38257.80871095738, 40007.0, 41420.964475888344, 51274.23088653748, 54233.72883135559, 55020.174697378774, 55076.0, 55174.0, 7363.447741419308, 7547.05661792276, 7548.320651141424, 7792.922070779219, 7805.32185580103, 11187.216894817284, 11204.439778010903, 11344.602938897098, 11374.601888933848, 11515.712107197052, 9737.80524389494, 9942.56031167286, 9972.252081093802, 10027.699169024942, 10336.431496267476, 10215.7446063849, 10221.846672300071, 10261.640842570474, 10267.486625668538, 10427.94786026065, 6955.339242771928, 7199.640017998974, 7483.962580187064, 7617.885731714139, 9854.950725246328, 9865.556049977911, 11080.0, 11263.605773797877, 11320.830187547359, 11335.603253886075, 10382.792344153226, 10607.94696026515, 10678.786424271315, 10916.945415272872, 11160.888391115985], "value": 8237.144131113837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.687095403534, 1443.732909411759, 1512.7579587266039, 1694.7034269002922, 1715.957101072473, 6937.791866244008, 7142.071530701015, 7712.15166331703, 8269.25576698097, 8274.669013239463, 7520.586367749772, 7782.494137881034, 7788.415868809848, 7799.142094369629, 7953.880691789628, 2149.7957694019083, 2209.569134018867, 2222.2666520048424, 2224.0, 2225.9220927267547, 1889.2443022790924, 2113.0913707105938, 2176.0, 2181.3674034529968, 2185.0822654485155, 2232.151782322715, 2236.7763223677666, 2241.742199647037, 2242.6523888797265, 2244.932652020434, 2117.544727883505, 2156.7196264485597, 2156.8705877647235, 2157.223399576151, 2158.892055397224, 2159.762426133124, 2160.654295312758, 2169.6853956176424, 2185.169635538501, 2186.497105665706, 7976.122626511079, 8032.0, 8040.0, 8041.959790201012, 8051.194880511961, 4498.977505112453, 4613.331066995267, 4618.630509559227, 4628.976855115703, 4690.7654617269, 2774.833509989407, 2807.410443806803, 2857.2999615094227, 2895.7683385329124, 2904.8111872728205, 886.2245535156737, 892.3441270666056, 909.7589138878203, 913.9086091390874, 916.6333466613341, 10781.514831832586, 11062.336259824437, 11063.668089957315, 11064.889351106547, 11079.50142243601, 4240.5123410808155, 4243.6605071594195, 4380.7152535085115, 4411.911761764747, 4434.423524941737, 9458.716238512718, 9470.57382417779, 9471.621135154517, 9478.857817132748, 9482.478463684563, 4656.4412270527755, 4659.2312268476, 4665.113628410591, 4665.580097791215, 4671.392718946515, 3584.8386822592524, 4146.398772178018, 4173.08192197716, 4175.561566035522, 4177.352510360899, 3673.1368128489785, 3777.735558510878, 3855.0747820522874, 3863.5750067492554, 3908.394198899175, 1220.1154163231586, 1240.2248594628356, 1241.1312081542956, 1245.302630526908, 1256.214865708932, 811.9431639785277, 826.8635675113659, 832.2800777327689, 844.4046946902419, 851.3614788908319, 5162.9741851291255, 5220.451852555503, 5256.632035757537, 5338.439463856317, 5561.63849349791, 818.2308629887887, 863.2101627011215, 879.5294517433142, 886.3529623374973, 886.5788750343664, 3527.1358517162944, 3533.5406397168204, 3574.195805943642, 3589.9102522436624, 3641.417373220272, 9697.418154910727, 9957.950210248902, 9975.900240997496, 9990.050945160197, 10002.149817265468, 9216.170544651013, 9625.422474651543, 9628.037196280457, 9939.155171810333, 9944.204463642891, 7376.668049937765, 7415.1472580652835, 7507.887381689388, 7509.249075092447, 7560.281773231534, 5520.861978450571, 5529.585281104014, 5547.722613869371, 5736.71316434185, 5849.210356601804, 9198.632054717737, 9229.723108306762, 9316.254699624149, 9324.927633322255, 9333.626654933727, 8761.956190219009, 9150.0, 9157.725268241964, 9311.76720581991, 9468.195203407788, 6247.843803904939, 6758.594484330956, 6844.076049733421, 6972.09362782835, 6981.825454363681, 7671.616419178907, 7979.401544884041, 7980.760577182694, 7986.800329991796, 7992.040955085427, 6762.695678694372, 6857.862842743217, 6965.303469652994, 6972.372486476242, 7301.707931682673, 7062.258462861326, 7073.71705131789, 7099.787006389816, 7185.353318201387, 7192.2088570258275, 6019.247594050744, 6250.249970003629, 6261.279952805486, 6454.419102280817, 6541.771038013647, 4743.573078422958, 4806.351142595705, 4828.324034635224, 4872.537108974641, 4907.214845624683, 4506.684532082722, 4557.680962332605, 4560.703554268962, 4682.2508398656055, 4684.203685373563], "value": 4213.030247800831, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.71432139467504, 305.625608629429, 309.28863613688526, 309.7939869986459, 310.1687477560138, 4882.658213925027, 4886.507880980699, 4906.288588154715, 4917.631177661677, 4927.778249978751, 4821.541953514419, 4855.514448555143, 4856.490068542805, 4860.538248866361, 4871.415430148379, 3624.8368823402943, 3732.4774531565467, 3740.2706472237924, 3826.8086595670243, 3830.923381532368, 3779.8677046303383, 3804.7146464015173, 3822.5986271441516, 3825.6939444844347, 3837.2709185254853, 3599.1002249437643, 3793.924121517568, 3796.443458182146, 3813.2945405100145, 3816.694664426839, 3691.280200360917, 3806.1626442182674, 3808.923821523554, 3817.6564109230026, 3819.8472061117523, 3389.0510657016075, 3400.0989737719524, 3407.778494397881, 3409.16475463513, 3411.1301618087477, 4728.895641439561, 4736.431628204603, 4736.550027747393, 4739.3933336664695, 4748.169070412699, 4273.87178384649, 4314.331278651814, 4332.696711230215, 4355.80398882051, 4369.191699535564, 4961.231009193581, 5000.499950004971, 5009.674371165899, 5014.52362025607, 5017.724025178613, 3682.705383569335, 3702.5927148013698, 3704.703084087856, 3710.6474884885893, 3726.1988672435555, 5102.744862756885, 5241.31862857826, 5246.23929530216, 5254.6847189168775, 5270.367555893279, 3753.7560058596105, 3778.074371778877, 3788.431735239736, 3788.6969042476535, 3814.7138964577766, 3831.0, 3852.4799152114106, 3876.8449262029203, 3879.1853710721066, 3883.10688541637, 4354.0, 4355.5208927018, 4356.586124318248, 4364.410804541346, 4364.607185353333, 4871.244957031666, 5015.222640490657, 5022.59819214462, 5029.974850125726, 5052.8484145475695, 1303.1659737767836, 1330.1909403211625, 1330.3082397153426, 1332.3005422153446, 1341.9396127174105, 879.5162660536679, 881.7354793561906, 910.517425764346, 911.3165126155384, 911.9726408207764, 4342.652587792969, 4372.982641977026, 4486.977565112219, 4492.460904691458, 4526.0, 5166.276721259027, 5173.456787037382, 5197.376314842243, 5199.7140157291715, 5251.0, 4323.3947247385395, 4505.977470112694, 4529.456465224194, 4530.116627257653, 4622.445306563234, 2730.290124567587, 2740.8629568521496, 2766.2531116598807, 2775.653043369579, 2780.7219278072434, 4975.726335051607, 4992.5756310714, 4993.6005119590345, 5012.8496145115705, 5019.749012549358, 5117.488251174853, 5335.519803217651, 5356.4107948125675, 5393.1370980642905, 5402.729863506887, 4953.2570114483115, 4960.975195124001, 4965.3048573199785, 5001.4998500149695, 5002.499750025041, 5163.199704045879, 5184.377874654915, 5209.775702709784, 5230.241614965885, 5237.685738855681, 4836.0, 5143.6399452037995, 5188.9740551297, 5194.7922083116255, 5195.3246078009615, 4938.827141050046, 4947.901041979212, 4949.133901567283, 4957.529034741694, 5018.347614810052, 3721.330160571015, 3728.366177749735, 3754.1740817021305, 3758.6241375862196, 3760.6615404613717, 5099.311592935054, 5242.239875217996, 5248.448912864094, 5255.684658920477, 5267.394249661338, 5075.28945947575, 5082.542571168613, 5088.211327244283, 5091.0, 5100.617453690914, 5036.420811606712, 5078.4667609900425, 5080.49195080489, 5087.160618497906, 5140.691558506502, 4801.5438533339275, 4809.2305231162845, 4969.726665033458, 4993.526909561615, 5009.123403404463, 4940.036692844861, 5104.464031276662, 5128.922646412123, 5136.434992150786, 5143.92284115746, 4961.355023846877, 4991.850244492671, 4993.875153121201, 5001.549860512426, 5003.224500202475], "value": 3852.2509293078388, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.4853394483496, 649.2792999770254, 700.3066963705929, 712.4941291682904, 764.8967389402433, 12321.507139714411, 12332.691682707937, 12385.194962327443, 12454.0, 12490.12569120162, 13767.518136865212, 14496.710065798678, 14507.92746036271, 14900.254987250648, 14905.254737263147, 623.3735096228285, 774.2180397798209, 794.0233512779271, 811.2982270336175, 815.8735396013598, 798.0901771979948, 807.1524898856206, 807.5679511461369, 808.3613944983456, 809.0776514773163, 843.0726201178685, 849.9490030598153, 851.1956201389689, 852.0925214646381, 852.6674596907211, 644.4135836388887, 802.7752229375726, 833.9992009588458, 838.8825564420986, 839.5424493650944, 618.6813790897692, 621.7295476467751, 622.0389498225207, 622.0917460507665, 622.7540121651967, 13827.585172444842, 13897.235652039133, 13912.51306204278, 13922.93038534811, 13936.721265574632, 13115.934420327838, 13926.93036534811, 14038.859611403857, 14111.506097286649, 14155.504557340444, 10831.404272764994, 10841.376426726127, 10889.618863339745, 10897.275681079731, 11067.833982490269, 3315.1712071982006, 3339.248669049469, 3381.644927282649, 3387.220939183977, 3452.9309413811834, 63179.0, 63768.681156594386, 63781.681091594706, 64786.67606661984, 64972.0, 10301.536430860479, 10563.841542376871, 10670.786584268271, 10854.185936054828, 11015.83476247857, 20238.49403764888, 20543.8972805138, 20616.278430254868, 20644.69032964507, 20645.070971806304, 6764.966175169093, 6845.589264644137, 6969.0, 7078.433725302067, 7107.857842843217, 3731.029932217589, 3752.5309336332957, 3803.3344164771056, 3804.1821008482757, 3905.9804700976315, 2858.4140251248446, 2949.2921698792134, 2982.537706655472, 2984.0301901881976, 2985.358147998191, 479.8032806549298, 491.2680106641079, 497.2367416016397, 498.0288437546798, 505.375860811897, 450.4122120632606, 456.2859125468631, 465.30669302738676, 481.1675800864524, 562.7355143082744, 7881.527108373516, 8084.83830323402, 8106.392020598479, 8120.269175774209, 8193.95903020493, 435.5296280017573, 473.02084684702334, 507.30115784029346, 548.1257394455837, 550.2901257378018, 3681.813010985121, 3832.405977073558, 3841.250956063541, 3895.9610403896163, 3896.532416110085, 73183.2681673187, 85957.57021214854, 90712.54643726739, 94282.0571794287, 97308.51345743226, 81404.59297703592, 82378.0, 84840.0, 85011.0, 85472.14527854642, 45185.54814451879, 70669.0, 72710.63644681743, 74226.0, 74530.0, 7744.6902123914415, 7988.360931125612, 8055.395845311742, 8200.302974247017, 8335.708250211213, 44535.331970019826, 44933.775331123135, 45361.546384537054, 45563.772181138884, 47305.526944731486, 57342.42657573538, 62802.0, 63228.05157922543, 63281.0, 63721.68139159275, 11425.31448113116, 11507.884921151017, 11640.88359116398, 11707.531698731955, 12217.77822217771, 12698.746025079632, 12941.741165176456, 13013.609591712264, 13102.737945241233, 13304.733905322033, 11697.473613687234, 11767.529298828293, 12056.518972237775, 12134.33261170644, 12389.62831115068, 11609.941950290195, 12135.575254866037, 12142.08934329911, 12532.122751407314, 12643.114981951176, 11790.23363481371, 12022.458989345676, 12026.398680066137, 12054.15620906528, 12799.104062715893, 9330.486823224517, 10284.794304113726, 10653.520591573242, 11026.283291586022, 11112.277701949348, 9854.901450985684, 10092.747681308027, 10247.897521024992, 10373.429461379395, 10390.272680912263], "value": 6594.14889079458, "unit": "score"}], "name": "dromaeo_css", "value": 6394.312318016659, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 114.0, 115.0, 112.0, 113.0, 112.0, 113.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 125.0, 175.0, 125.0, 124.0, 149.0, 125.0, 162.0, 123.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 161.0, 164.0, 162.0, 161.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 116.0, 78.0, 76.0, 74.0, 75.0, 108.0, 76.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 111.0, 85.0, 84.0, 85.0, 114.0, 84.0, 86.0, 125.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0, 66.0, 66.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 78.0, 78.0, 82.0, 79.0, 79.0, 78.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 125.0, 126.0, 128.0, 127.0, 128.0, 127.0, 129.0, 219.0, 138.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 166.0, 165.0, 166.0, 162.0, 165.0, 161.0, 161.0, 162.0, 163.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 64.0, 65.0, 66.0, 66.0, 65.0, 64.0, 75.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1527.5, "alertThreshold": 2.0}]} 09:29:15 INFO - Return code: 0 09:29:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:15 INFO - # TBPL SUCCESS # 09:29:15 INFO - Running post-action listener: _resource_record_post_action 09:29:15 INFO - ##### 09:29:15 INFO - ##### Finished run-tests step (success) 09:29:15 INFO - ##### 09:29:15 INFO - Running post-run listener: _resource_record_post_run 09:29:15 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 535805952; Read time: 28; Write time: 411172 09:29:15 INFO - TinderboxPrint: CPU usage
13.0% 09:29:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 09:29:15 INFO - TinderboxPrint: I/O write bytes / time
535,805,952 / 411,172 09:29:15 INFO - TinderboxPrint: CPU idle
8,336.8 (87.0%) 09:29:15 INFO - TinderboxPrint: CPU user
1,200.9 (12.5%) 09:29:15 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:15 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246890496; Read time: 0; Write time: 217572 09:29:15 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288915456; Read time: 20; Write time: 193600 09:29:16 INFO - Running post-run listener: _upload_blobber_files 09:29:16 INFO - Blob upload gear active. 09:29:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:16 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 09:29:16 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:16 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:17 INFO - (blobuploader) - INFO - Done attempting. 09:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:26 INFO - (blobuploader) - INFO - Done attempting. 09:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:26 INFO - (blobuploader) - INFO - Done attempting. 09:29:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:26 INFO - Return code: 0 09:29:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7813d2ea525691a9012c569f6535b5b0fd198a110d4432de247994d7412fdf129ad04b5896a5cdbea75e112148cd124f0605098d6a3799d6d95216ebc4b3c052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2e9175072fcd9bf108b31d11800415c02274f828c3ae02779fff197b25ec1db311fa42fe52aa2ec209d949641b3a26c0b6a360fb090e40d3259059206a3687e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/88e6e4241ce2f1283255527a33a86080196eb26458ec84a13e8da9b0d481309b2424c701070b99fe435aa358871692a8233294f714d479964966c7d0f820ad2a"} 09:29:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:26 INFO - Contents: 09:29:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7813d2ea525691a9012c569f6535b5b0fd198a110d4432de247994d7412fdf129ad04b5896a5cdbea75e112148cd124f0605098d6a3799d6d95216ebc4b3c052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2e9175072fcd9bf108b31d11800415c02274f828c3ae02779fff197b25ec1db311fa42fe52aa2ec209d949641b3a26c0b6a360fb090e40d3259059206a3687e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/88e6e4241ce2f1283255527a33a86080196eb26458ec84a13e8da9b0d481309b2424c701070b99fe435aa358871692a8233294f714d479964966c7d0f820ad2a"} 09:29:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:26 INFO - Copying logs to upload dir... 09:29:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.206282 ========= master_lag: 4.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-14 09:29:31.319836) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 09:29:31.323529) ========= 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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7813d2ea525691a9012c569f6535b5b0fd198a110d4432de247994d7412fdf129ad04b5896a5cdbea75e112148cd124f0605098d6a3799d6d95216ebc4b3c052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2e9175072fcd9bf108b31d11800415c02274f828c3ae02779fff197b25ec1db311fa42fe52aa2ec209d949641b3a26c0b6a360fb090e40d3259059206a3687e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/88e6e4241ce2f1283255527a33a86080196eb26458ec84a13e8da9b0d481309b2424c701070b99fe435aa358871692a8233294f714d479964966c7d0f820ad2a"} build_url:https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/axLEqki6T1G9oOxRS8GXKg/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/Autoland-Non-PGO/sha512/7813d2ea525691a9012c569f6535b5b0fd198a110d4432de247994d7412fdf129ad04b5896a5cdbea75e112148cd124f0605098d6a3799d6d95216ebc4b3c052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2e9175072fcd9bf108b31d11800415c02274f828c3ae02779fff197b25ec1db311fa42fe52aa2ec209d949641b3a26c0b6a360fb090e40d3259059206a3687e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/88e6e4241ce2f1283255527a33a86080196eb26458ec84a13e8da9b0d481309b2424c701070b99fe435aa358871692a8233294f714d479964966c7d0f820ad2a"}' ========= master_lag: 0.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 09:29:31.525109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:29:31.525433) ========= 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=920470bf432669ad7e3a5387000001ca-1468509843.768716-40593950 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004392 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:29:31.542365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 09:29:31.542783) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 09:29:31.543131) ========= ========= Total master_lag: 4.74 =========