builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1468942325.12 results: success (0) buildid: 20160719071020 builduid: 52b20fb24618443e8fe91174c3a05156 revision: 5a91e5b49be3c1ba401b057e90c92d7488e3647d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.115537) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.115968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.116245) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.131452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.131983) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 08:32:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.9M=0.001s 2016-07-19 08:32:05 (22.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.290061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.290378) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018188 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.340258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:05.340643) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a91e5b49be3c1ba401b057e90c92d7488e3647d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a91e5b49be3c1ba401b057e90c92d7488e3647d --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=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 08:32:06,052 truncating revision to first 12 chars 2016-07-19 08:32:06,053 Setting DEBUG logging. 2016-07-19 08:32:06,053 attempt 1/10 2016-07-19 08:32:06,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a91e5b49be3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 08:32:06,695 unpacking tar archive at: mozilla-central-5a91e5b49be3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.977943 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:06.333180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:06.333592) ========= script_repo_revision: 5a91e5b49be3c1ba401b057e90c92d7488e3647d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:06.334054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:06.334374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 08:32:06.344188) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-19 08:32:06.344508) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:32:07 INFO - MultiFileLogger online at 20160719 08:32:07 in /builds/slave/test 08:32:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:32:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:32:07 INFO - {'append_to_log': False, 08:32:07 INFO - 'base_work_dir': '/builds/slave/test', 08:32:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:32:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:32:07 INFO - 'branch': 'Firefox-Non-PGO', 08:32:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:32:07 INFO - 'config_files': ('talos/linux_config.py',), 08:32:07 INFO - 'default_actions': ('clobber', 08:32:07 INFO - 'read-buildbot-config', 08:32:07 INFO - 'download-and-extract', 08:32:07 INFO - 'populate-webroot', 08:32:07 INFO - 'create-virtualenv', 08:32:07 INFO - 'install', 08:32:07 INFO - 'run-tests'), 08:32:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:32:07 INFO - 'download_minidump_stackwalk': True, 08:32:07 INFO - 'download_symbols': 'ondemand', 08:32:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:32:07 INFO - 'tooltool.py': '/tools/tooltool.py', 08:32:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:32:07 INFO - '/tools/misc-python/virtualenv.py')}, 08:32:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:32:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:32:07 INFO - 'installer_path': 'installer.exe', 08:32:07 INFO - 'log_level': 'info', 08:32:07 INFO - 'log_name': 'talos', 08:32:07 INFO - 'log_to_console': True, 08:32:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:32:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:32:07 INFO - 'opt_config_files': (), 08:32:07 INFO - 'pip_index': False, 08:32:07 INFO - 'sps_profile': False, 08:32:07 INFO - 'sps_profile_interval': 0, 08:32:07 INFO - 'suite': 'dromaeojs', 08:32:07 INFO - 'system_bits': '32', 08:32:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:32:07 INFO - 'title': 'talos-linux64-ix-068', 08:32:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:32:07 INFO - 'use_talos_json': True, 08:32:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:32:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:32:07 INFO - 'work_dir': 'build'} 08:32:07 INFO - ##### 08:32:07 INFO - ##### Running clobber step. 08:32:07 INFO - ##### 08:32:07 INFO - Running pre-action listener: _resource_record_pre_action 08:32:07 INFO - Running main action method: clobber 08:32:07 INFO - rmtree: /builds/slave/test/build 08:32:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:32:07 INFO - Running post-action listener: _resource_record_post_action 08:32:07 INFO - ##### 08:32:07 INFO - ##### Finished clobber step (success) 08:32:07 INFO - ##### 08:32:07 INFO - ##### 08:32:07 INFO - ##### Running read-buildbot-config step. 08:32:07 INFO - ##### 08:32:07 INFO - Running pre-action listener: _resource_record_pre_action 08:32:07 INFO - Running main action method: read_buildbot_config 08:32:07 INFO - Using buildbot properties: 08:32:07 INFO - { 08:32:07 INFO - "project": "", 08:32:07 INFO - "product": "firefox", 08:32:07 INFO - "script_repo_revision": "production", 08:32:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:32:07 INFO - "repository": "", 08:32:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:32:07 INFO - "buildid": "20160719071020", 08:32:07 INFO - "pgo_build": "False", 08:32:07 INFO - "basedir": "/builds/slave/test", 08:32:07 INFO - "buildnumber": 154, 08:32:07 INFO - "slavename": "talos-linux64-ix-068", 08:32:07 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:32:07 INFO - "platform": "ubuntu64_hw", 08:32:07 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:32:07 INFO - "branch": "mozilla-central", 08:32:07 INFO - "repo_path": "mozilla-central", 08:32:07 INFO - "revision": "5a91e5b49be3c1ba401b057e90c92d7488e3647d", 08:32:07 INFO - "stage_platform": "linux64", 08:32:07 INFO - "builduid": "52b20fb24618443e8fe91174c3a05156", 08:32:07 INFO - "slavebuilddir": "test" 08:32:07 INFO - } 08:32:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:32:07 INFO - Running post-action listener: _resource_record_post_action 08:32:07 INFO - ##### 08:32:07 INFO - ##### Finished read-buildbot-config step (success) 08:32:07 INFO - ##### 08:32:07 INFO - ##### 08:32:07 INFO - ##### Running download-and-extract step. 08:32:07 INFO - ##### 08:32:07 INFO - Running pre-action listener: _resource_record_pre_action 08:32:07 INFO - Running main action method: download_and_extract 08:32:07 INFO - mkdir: /builds/slave/test/build/tests 08:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:07 INFO - https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:32:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:32:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:32:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:08 INFO - Downloaded 1361 bytes. 08:32:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:32:08 INFO - Using the following test package requirements: 08:32:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:32:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:32:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:32:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'jsshell-linux-x86_64.zip'], 08:32:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:32:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:32:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:32:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:32:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:32:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:32:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:32:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:32:08 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')]} 08:32:08 INFO - https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:32:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:32:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:32:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:09 INFO - Downloaded 22765022 bytes. 08:32:09 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'] 08:32:09 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 08:32:10 INFO - Return code: 0 08:32:10 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 08:32: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')]} 08:32:10 INFO - https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:32:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:32:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:32:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:11 INFO - Downloaded 22765022 bytes. 08:32:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:32:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:32:13 INFO - Return code: 0 08:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:13 INFO - https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:32:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:32:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:32:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:13 INFO - Downloaded 14403317 bytes. 08:32:13 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'] 08:32:13 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 08:32:14 INFO - Return code: 0 08:32: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')]} 08:32:14 INFO - https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:32:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:32:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:32:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/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 08:32:15 INFO - Downloaded 57139078 bytes. 08:32:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:32:15 INFO - mkdir: /builds/slave/test/properties 08:32:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:15 INFO - Contents: 08:32:15 INFO - build_url:https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:32:16 INFO - Running post-action listener: _resource_record_post_action 08:32:16 INFO - Running post-action listener: set_extra_try_arguments 08:32:16 INFO - ##### 08:32:16 INFO - ##### Finished download-and-extract step (success) 08:32:16 INFO - ##### 08:32:16 INFO - ##### 08:32:16 INFO - ##### Running populate-webroot step. 08:32:16 INFO - ##### 08:32:16 INFO - Running pre-action listener: _resource_record_pre_action 08:32:16 INFO - Running main action method: populate_webroot 08:32:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:32:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:32:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:32:16 INFO - '--tppagecycles', 08:32:16 INFO - '7'], 08:32:16 INFO - 'tests': ['tsvgm']}}, 08:32:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['damp', 'tps']}, 08:32:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'tests': ['damp', 'tps']}, 08:32:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['dromaeo_dom']}, 08:32:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['basic_compositor_video']}, 08:32:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:32:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['a11yr', 08:32:16 INFO - 'ts_paint', 08:32:16 INFO - 'tpaint', 08:32:16 INFO - 'sessionrestore', 08:32:16 INFO - 'sessionrestore_no_auto_restore', 08:32:16 INFO - 'tabpaint']}, 08:32:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['tsvgx', 08:32:16 INFO - 'tsvgr_opacity', 08:32:16 INFO - 'tart', 08:32:16 INFO - 'tscrollx', 08:32:16 INFO - 'cart']}, 08:32:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:16 INFO - 'tsvgr_opacity', 08:32:16 INFO - 'tart', 08:32:16 INFO - 'tscrollx', 08:32:16 INFO - 'cart']}, 08:32:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'talos_options': ['--disable-e10s'], 08:32:16 INFO - 'tests': ['tp5o']}, 08:32:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'tests': ['tp5o']}, 08:32:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'talos_options': ['--disable-e10s', 08:32:16 INFO - '--xperf_path', 08:32:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:16 INFO - 'tests': ['tp5n']}, 08:32:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:16 INFO - 'talos_options': ['--xperf_path', 08:32:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:16 INFO - 'tests': ['tp5n']}}, 08:32:16 INFO - 'talos.zip': {'path': '', 08:32:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:32:16 INFO - Running post-action listener: _resource_record_post_action 08:32:16 INFO - ##### 08:32:16 INFO - ##### Finished populate-webroot step (success) 08:32:16 INFO - ##### 08:32:16 INFO - ##### 08:32:16 INFO - ##### Running create-virtualenv step. 08:32:16 INFO - ##### 08:32:16 INFO - Running pre-action listener: _resource_record_pre_action 08:32:16 INFO - Running main action method: create_virtualenv 08:32:16 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:16 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 08:32:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:32:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:16 INFO - Using real prefix '/usr' 08:32:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:18 INFO - Installing pip.................done. 08:32:18 INFO - Return code: 0 08:32:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:18 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:18 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 08:32:18 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 08:32:18 INFO - Using env: {'DISPLAY': ':0', 08:32:18 INFO - 'HOME': '/home/cltbld', 08:32:18 INFO - 'LANG': 'en_US.UTF-8', 08:32:18 INFO - 'LANGUAGE': 'en_US:en', 08:32:18 INFO - 'LOGNAME': 'cltbld', 08:32:18 INFO - 'MAIL': '/var/mail/cltbld', 08:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:18 INFO - 'MOZ_NO_REMOTE': '1', 08:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:18 INFO - 'NO_EM_RESTART': '1', 08:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:18 INFO - 'PWD': '/builds/slave/test', 08:32:18 INFO - 'SHELL': '/bin/bash', 08:32:18 INFO - 'SHLVL': '1', 08:32:18 INFO - 'TERM': 'linux', 08:32:18 INFO - 'TMOUT': '86400', 08:32:18 INFO - 'USER': 'cltbld', 08:32:18 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:18 INFO - '_': '/tools/buildbot/bin/python'} 08:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:19 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 08:32:19 INFO - Cleaning up... 08:32:19 INFO - Return code: 0 08:32:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32:19 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')]} 08:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 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')]} 08:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:19 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:19 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 08:32:19 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 08:32:19 INFO - Using env: {'DISPLAY': ':0', 08:32:19 INFO - 'HOME': '/home/cltbld', 08:32:19 INFO - 'LANG': 'en_US.UTF-8', 08:32:19 INFO - 'LANGUAGE': 'en_US:en', 08:32:19 INFO - 'LOGNAME': 'cltbld', 08:32:19 INFO - 'MAIL': '/var/mail/cltbld', 08:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:19 INFO - 'MOZ_NO_REMOTE': '1', 08:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:19 INFO - 'NO_EM_RESTART': '1', 08:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:19 INFO - 'PWD': '/builds/slave/test', 08:32:19 INFO - 'SHELL': '/bin/bash', 08:32:19 INFO - 'SHLVL': '1', 08:32:19 INFO - 'TERM': 'linux', 08:32:19 INFO - 'TMOUT': '86400', 08:32:19 INFO - 'USER': 'cltbld', 08:32:19 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:19 INFO - '_': '/tools/buildbot/bin/python'} 08:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:19 INFO - Downloading/unpacking psutil>=3.1.1 08:32:19 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 08:32:19 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 08:32:19 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 08:32:19 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 08:32:19 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 08:32:19 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 08:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:21 INFO - Installing collected packages: psutil 08:32:21 INFO - Running setup.py install for psutil 08:32:21 INFO - building 'psutil._psutil_linux' extension 08:32:21 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 08:32:21 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 08:32:21 INFO - building 'psutil._psutil_posix' extension 08:32:21 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 08:32:21 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 08:32:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:21 INFO - Successfully installed psutil 08:32:21 INFO - Cleaning up... 08:32:21 INFO - Return code: 0 08:32:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:32:21 INFO - Using env: {'DISPLAY': ':0', 08:32:21 INFO - 'HOME': '/home/cltbld', 08:32:21 INFO - 'LANG': 'en_US.UTF-8', 08:32:21 INFO - 'LANGUAGE': 'en_US:en', 08:32:21 INFO - 'LOGNAME': 'cltbld', 08:32:21 INFO - 'MAIL': '/var/mail/cltbld', 08:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:21 INFO - 'MOZ_NO_REMOTE': '1', 08:32:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:21 INFO - 'NO_EM_RESTART': '1', 08:32:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:21 INFO - 'PWD': '/builds/slave/test', 08:32:21 INFO - 'SHELL': '/bin/bash', 08:32:21 INFO - 'SHLVL': '1', 08:32:21 INFO - 'TERM': 'linux', 08:32:21 INFO - 'TMOUT': '86400', 08:32:21 INFO - 'USER': 'cltbld', 08:32:21 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:21 INFO - '_': '/tools/buildbot/bin/python'} 08:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:22 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 08:32:22 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 08:32:22 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 08:32:22 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 08:32:22 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 08:32:22 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 08:32:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:24 INFO - Installing collected packages: mozsystemmonitor 08:32:24 INFO - Running setup.py install for mozsystemmonitor 08:32:24 INFO - Successfully installed mozsystemmonitor 08:32:24 INFO - Cleaning up... 08:32:24 INFO - Return code: 0 08:32:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:32:24 INFO - Using env: {'DISPLAY': ':0', 08:32:24 INFO - 'HOME': '/home/cltbld', 08:32:24 INFO - 'LANG': 'en_US.UTF-8', 08:32:24 INFO - 'LANGUAGE': 'en_US:en', 08:32:24 INFO - 'LOGNAME': 'cltbld', 08:32:24 INFO - 'MAIL': '/var/mail/cltbld', 08:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:24 INFO - 'MOZ_NO_REMOTE': '1', 08:32:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:24 INFO - 'NO_EM_RESTART': '1', 08:32:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:24 INFO - 'PWD': '/builds/slave/test', 08:32:24 INFO - 'SHELL': '/bin/bash', 08:32:24 INFO - 'SHLVL': '1', 08:32:24 INFO - 'TERM': 'linux', 08:32:24 INFO - 'TMOUT': '86400', 08:32:24 INFO - 'USER': 'cltbld', 08:32:24 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:24 INFO - '_': '/tools/buildbot/bin/python'} 08:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:24 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:26 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:26 INFO - warning: no files found matching '*.txt' 08:32:26 INFO - no previously-included directories found matching 'build' 08:32:26 INFO - no previously-included directories found matching 'dist' 08:32:26 INFO - no previously-included directories found matching '.git*' 08:32:26 INFO - Installing collected packages: jsonschema, functools32 08:32:26 INFO - Running setup.py install for functools32 08:32:26 INFO - warning: no files found matching '*.txt' 08:32:26 INFO - no previously-included directories found matching 'build' 08:32:26 INFO - no previously-included directories found matching 'dist' 08:32:26 INFO - no previously-included directories found matching '.git*' 08:32:26 INFO - Successfully installed jsonschema functools32 08:32:26 INFO - Cleaning up... 08:32:26 INFO - Return code: 0 08:32:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:32:26 INFO - Using env: {'DISPLAY': ':0', 08:32:26 INFO - 'HOME': '/home/cltbld', 08:32:26 INFO - 'LANG': 'en_US.UTF-8', 08:32:26 INFO - 'LANGUAGE': 'en_US:en', 08:32:26 INFO - 'LOGNAME': 'cltbld', 08:32:26 INFO - 'MAIL': '/var/mail/cltbld', 08:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:26 INFO - 'MOZ_NO_REMOTE': '1', 08:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:26 INFO - 'NO_EM_RESTART': '1', 08:32:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:26 INFO - 'PWD': '/builds/slave/test', 08:32:26 INFO - 'SHELL': '/bin/bash', 08:32:26 INFO - 'SHLVL': '1', 08:32:26 INFO - 'TERM': 'linux', 08:32:26 INFO - 'TMOUT': '86400', 08:32:26 INFO - 'USER': 'cltbld', 08:32:26 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:26 INFO - '_': '/tools/buildbot/bin/python'} 08:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:27 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:27 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 08:32:27 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 08:32:27 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 08:32:27 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 08:32:27 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 08:32:27 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 08:32:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:29 INFO - Downloading docopt-0.6.1.tar.gz 08:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:29 INFO - Running setup.py install for blobuploader 08:32:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:29 INFO - Running setup.py install for requests 08:32:30 INFO - Running setup.py install for docopt 08:32:30 INFO - Successfully installed blobuploader requests docopt 08:32:30 INFO - Cleaning up... 08:32:30 INFO - Return code: 0 08:32:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:30 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')]} 08:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:30 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')]} 08:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:30 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:30 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 08:32:30 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 08:32:30 INFO - Using env: {'DISPLAY': ':0', 08:32:30 INFO - 'HOME': '/home/cltbld', 08:32:30 INFO - 'LANG': 'en_US.UTF-8', 08:32:30 INFO - 'LANGUAGE': 'en_US:en', 08:32:30 INFO - 'LOGNAME': 'cltbld', 08:32:30 INFO - 'MAIL': '/var/mail/cltbld', 08:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:30 INFO - 'MOZ_NO_REMOTE': '1', 08:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:30 INFO - 'NO_EM_RESTART': '1', 08:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:30 INFO - 'PWD': '/builds/slave/test', 08:32:30 INFO - 'SHELL': '/bin/bash', 08:32:30 INFO - 'SHLVL': '1', 08:32:30 INFO - 'TERM': 'linux', 08:32:30 INFO - 'TMOUT': '86400', 08:32:30 INFO - 'USER': 'cltbld', 08:32:30 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:30 INFO - '_': '/tools/buildbot/bin/python'} 08:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:30 INFO - Running setup.py (path:/tmp/pip-7EP5m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:30 INFO - Running setup.py (path:/tmp/pip-d3d11v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:30 INFO - Running setup.py (path:/tmp/pip-xICO5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:30 INFO - Running setup.py (path:/tmp/pip-wgeMUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:30 INFO - Running setup.py (path:/tmp/pip-kCk8fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:30 INFO - Running setup.py (path:/tmp/pip-rFNgLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:31 INFO - Running setup.py (path:/tmp/pip-W8gTLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:31 INFO - Running setup.py (path:/tmp/pip-Ua5VqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:31 INFO - Running setup.py (path:/tmp/pip-dWZI9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:31 INFO - Running setup.py (path:/tmp/pip-Q7rOLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:31 INFO - Running setup.py (path:/tmp/pip-AnKocB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:31 INFO - Running setup.py (path:/tmp/pip-wA3wEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:31 INFO - Running setup.py (path:/tmp/pip-xXVR__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:31 INFO - Running setup.py (path:/tmp/pip-fuvoC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:31 INFO - Running setup.py (path:/tmp/pip-QOrEkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:31 INFO - Running setup.py (path:/tmp/pip-sC0IHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:31 INFO - Running setup.py (path:/tmp/pip-Hpvw32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:31 INFO - Running setup.py install for manifestparser 08:32:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:31 INFO - Running setup.py install for mozcrash 08:32:32 INFO - Running setup.py install for mozdebug 08:32:32 INFO - Running setup.py install for mozdevice 08:32:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for mozfile 08:32:32 INFO - Running setup.py install for mozhttpd 08:32:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for mozinfo 08:32:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for mozInstall 08:32:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for mozleak 08:32:32 INFO - Running setup.py install for mozlog 08:32:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for moznetwork 08:32:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:32 INFO - Running setup.py install for mozprocess 08:32:33 INFO - Running setup.py install for mozprofile 08:32:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:33 INFO - Running setup.py install for mozrunner 08:32:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:33 INFO - Running setup.py install for mozscreenshot 08:32:33 INFO - Running setup.py install for moztest 08:32:33 INFO - Running setup.py install for mozversion 08:32:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:33 INFO - Cleaning up... 08:32:33 INFO - Return code: 0 08:32:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:33 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')]} 08:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:33 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')]} 08:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:33 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:33 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 08:32:33 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 08:32:33 INFO - Using env: {'DISPLAY': ':0', 08:32:33 INFO - 'HOME': '/home/cltbld', 08:32:33 INFO - 'LANG': 'en_US.UTF-8', 08:32:33 INFO - 'LANGUAGE': 'en_US:en', 08:32:33 INFO - 'LOGNAME': 'cltbld', 08:32:33 INFO - 'MAIL': '/var/mail/cltbld', 08:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:33 INFO - 'MOZ_NO_REMOTE': '1', 08:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:33 INFO - 'NO_EM_RESTART': '1', 08:32:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:33 INFO - 'PWD': '/builds/slave/test', 08:32:33 INFO - 'SHELL': '/bin/bash', 08:32:33 INFO - 'SHLVL': '1', 08:32:33 INFO - 'TERM': 'linux', 08:32:33 INFO - 'TMOUT': '86400', 08:32:33 INFO - 'USER': 'cltbld', 08:32:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:33 INFO - '_': '/tools/buildbot/bin/python'} 08:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:33 INFO - Running setup.py (path:/tmp/pip-TvrEZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:33 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)) 08:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:33 INFO - Running setup.py (path:/tmp/pip-xiPUlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:33 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)) 08:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:33 INFO - Running setup.py (path:/tmp/pip-raGdmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:34 INFO - Running setup.py (path:/tmp/pip-aqKmlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:34 INFO - Running setup.py (path:/tmp/pip-HhHyWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:34 INFO - Running setup.py (path:/tmp/pip-b57f9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:34 INFO - Running setup.py (path:/tmp/pip-vsa73W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:34 INFO - Running setup.py (path:/tmp/pip-9MJyZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:34 INFO - Running setup.py (path:/tmp/pip-z6gfb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:34 INFO - Running setup.py (path:/tmp/pip-tByUJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:34 INFO - Running setup.py (path:/tmp/pip-nK8tJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:34 INFO - Running setup.py (path:/tmp/pip-5g6nkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:34 INFO - Running setup.py (path:/tmp/pip-5wclgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:34 INFO - Running setup.py (path:/tmp/pip-5XonwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:34 INFO - Running setup.py (path:/tmp/pip-PW76cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:34 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)) 08:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:34 INFO - Running setup.py (path:/tmp/pip-LYIKCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:35 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)) 08:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:35 INFO - Running setup.py (path:/tmp/pip-dhLsmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:35 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)) 08:32:35 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)) 08:32:35 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)) 08:32:35 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)) 08:32:35 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)) 08:32:35 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)) 08:32:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:35 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 08:32:37 INFO - Downloading blessings-1.6.tar.gz 08:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:37 INFO - Installing collected packages: blessings 08:32:37 INFO - Running setup.py install for blessings 08:32:37 INFO - Successfully installed blessings 08:32:37 INFO - Cleaning up... 08:32:37 INFO - Return code: 0 08:32:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:37 INFO - Reading from file tmpfile_stdout 08:32:37 INFO - Current package versions: 08:32:37 INFO - argparse == 1.2.1 08:32:37 INFO - blessings == 1.6 08:32:37 INFO - blobuploader == 1.2.4 08:32:37 INFO - docopt == 0.6.1 08:32:37 INFO - functools32 == 3.2.3-2 08:32:37 INFO - jsonschema == 2.5.1 08:32:37 INFO - manifestparser == 1.1 08:32:37 INFO - mozInstall == 1.12 08:32:37 INFO - mozcrash == 0.17 08:32:37 INFO - mozdebug == 0.1 08:32:37 INFO - mozdevice == 0.48 08:32:37 INFO - mozfile == 1.2 08:32:37 INFO - mozhttpd == 0.7 08:32:37 INFO - mozinfo == 0.9 08:32:37 INFO - mozleak == 0.1 08:32:37 INFO - mozlog == 3.3 08:32:37 INFO - moznetwork == 0.27 08:32:37 INFO - mozprocess == 0.23 08:32:37 INFO - mozprofile == 0.28 08:32:37 INFO - mozrunner == 6.12 08:32:37 INFO - mozscreenshot == 0.1 08:32:37 INFO - mozsystemmonitor == 0.3 08:32:37 INFO - moztest == 0.7 08:32:37 INFO - mozversion == 1.4 08:32:37 INFO - psutil == 3.1.1 08:32:37 INFO - requests == 1.2.3 08:32:37 INFO - wsgiref == 0.1.2 08:32:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:37 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 08:32:37 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 08:32:37 INFO - Using env: {'DISPLAY': ':0', 08:32:37 INFO - 'HOME': '/home/cltbld', 08:32:37 INFO - 'LANG': 'en_US.UTF-8', 08:32:37 INFO - 'LANGUAGE': 'en_US:en', 08:32:37 INFO - 'LOGNAME': 'cltbld', 08:32:37 INFO - 'MAIL': '/var/mail/cltbld', 08:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:37 INFO - 'MOZ_NO_REMOTE': '1', 08:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:37 INFO - 'NO_EM_RESTART': '1', 08:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:37 INFO - 'PWD': '/builds/slave/test', 08:32:37 INFO - 'SHELL': '/bin/bash', 08:32:37 INFO - 'SHLVL': '1', 08:32:37 INFO - 'TERM': 'linux', 08:32:37 INFO - 'TMOUT': '86400', 08:32:37 INFO - 'USER': 'cltbld', 08:32:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:37 INFO - '_': '/tools/buildbot/bin/python'} 08:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 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)) 08:32:37 INFO - Cleaning up... 08:32:37 INFO - Return code: 0 08:32:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:37 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 0x1516030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', '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 08:32:37 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 08:32:37 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 08:32:37 INFO - Using env: {'DISPLAY': ':0', 08:32:37 INFO - 'HOME': '/home/cltbld', 08:32:37 INFO - 'LANG': 'en_US.UTF-8', 08:32:37 INFO - 'LANGUAGE': 'en_US:en', 08:32:37 INFO - 'LOGNAME': 'cltbld', 08:32:37 INFO - 'MAIL': '/var/mail/cltbld', 08:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:37 INFO - 'MOZ_NO_REMOTE': '1', 08:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:37 INFO - 'NO_EM_RESTART': '1', 08:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:37 INFO - 'PWD': '/builds/slave/test', 08:32:37 INFO - 'SHELL': '/bin/bash', 08:32:37 INFO - 'SHLVL': '1', 08:32:37 INFO - 'TERM': 'linux', 08:32:37 INFO - 'TMOUT': '86400', 08:32:37 INFO - 'USER': 'cltbld', 08:32:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:37 INFO - '_': '/tools/buildbot/bin/python'} 08:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:37 INFO - Cleaning up... 08:32:37 INFO - Return code: 0 08:32:37 INFO - Running post-action listener: _resource_record_post_action 08:32:37 INFO - Running post-action listener: _start_resource_monitoring 08:32:37 INFO - Starting resource monitoring. 08:32:37 INFO - ##### 08:32:37 INFO - ##### Finished create-virtualenv step (success) 08:32:37 INFO - ##### 08:32:37 INFO - ##### 08:32:37 INFO - ##### Running install step. 08:32:37 INFO - ##### 08:32:37 INFO - Running pre-action listener: _resource_record_pre_action 08:32:37 INFO - Running main action method: install 08:32:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:38 INFO - Reading from file tmpfile_stdout 08:32:38 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:38 INFO - Reading from file tmpfile_stdout 08:32:38 INFO - Output received: 08:32:38 INFO - Usage: mozinstall [options] installer 08:32:38 INFO - Options: 08:32:38 INFO - -h, --help show this help message and exit 08:32:38 INFO - -d DEST, --destination=DEST 08:32:38 INFO - Directory to install application into. [default: 08:32:38 INFO - "/builds/slave/test"] 08:32:38 INFO - --app=APP Application being installed. [default: firefox] 08:32:38 INFO - mkdir: /builds/slave/test/build/application 08:32:38 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'] 08:32:38 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 08:32:54 INFO - Reading from file tmpfile_stdout 08:32:54 INFO - Output received: 08:32:54 INFO - /builds/slave/test/build/application/firefox/firefox 08:32:54 INFO - Running post-action listener: _resource_record_post_action 08:32:54 INFO - ##### 08:32:54 INFO - ##### Finished install step (success) 08:32:54 INFO - ##### 08:32:54 INFO - ##### 08:32:54 INFO - ##### Running run-tests step. 08:32:54 INFO - ##### 08:32:54 INFO - Running pre-action listener: _resource_record_pre_action 08:32:54 INFO - Running main action method: run_tests 08:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:54 INFO - Python 2.7.3 08:32:54 INFO - Return code: 0 08:32:54 INFO - grabbing minidump binary from tooltool 08:32:54 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')]} 08:32:54 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 0x17d53b0>, '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 0x17d62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d6740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:54 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 08:32:54 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 08:32:54 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 08:32:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:54 INFO - Return code: 0 08:32:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:32:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:32:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 08:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 08:32:54 INFO - Using env: {'DISPLAY': ':0', 08:32:54 INFO - 'HOME': '/home/cltbld', 08:32:54 INFO - 'LANG': 'en_US.UTF-8', 08:32:54 INFO - 'LANGUAGE': 'en_US:en', 08:32:54 INFO - 'LOGNAME': 'cltbld', 08:32:54 INFO - 'MAIL': '/var/mail/cltbld', 08:32:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:54 INFO - 'MOZ_NO_REMOTE': '1', 08:32:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:54 INFO - 'NO_EM_RESTART': '1', 08:32:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:54 INFO - 'PWD': '/builds/slave/test', 08:32:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:54 INFO - 'SHELL': '/bin/bash', 08:32:54 INFO - 'SHLVL': '1', 08:32:54 INFO - 'TERM': 'linux', 08:32:54 INFO - 'TMOUT': '86400', 08:32:54 INFO - 'USER': 'cltbld', 08:32:54 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085', 08:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:54 INFO - '_': '/tools/buildbot/bin/python'} 08:32:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 08:32:54 INFO - mozversion application_buildid: 20160719071020 08:32:54 INFO - mozversion application_changeset: 5a91e5b49be3c1ba401b057e90c92d7488e3647d 08:32:54 INFO - mozversion application_display_name: Nightly 08:32:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:54 INFO - mozversion application_name: Firefox 08:32:54 INFO - mozversion application_remotingname: firefox 08:32:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:32:54 INFO - mozversion application_vendor: Mozilla 08:32:54 INFO - mozversion application_version: 50.0a1 08:32:54 INFO - mozversion platform_buildid: 20160719071020 08:32:54 INFO - mozversion platform_changeset: 5a91e5b49be3c1ba401b057e90c92d7488e3647d 08:32:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:32:54 INFO - mozversion platform_version: 50.0a1 08:32:54 INFO - using testdate: 1468942374 08:32:54 INFO - actual date: 1468942374 08:32:54 INFO - starting webserver on 'localhost:45915' 08:32:54 INFO - SUITE-START | Running 2 tests 08:32:54 INFO - TEST-START | dromaeo_css 08:32:54 INFO - operating with platform_type : linux_ 08:32:54 INFO - Initialising browser for dromaeo_css test... 08:32:54 INFO - TEST-INFO | started process 4028 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEw4RDD/profile http://localhost:45915/getInfo.html) 08:32:59 INFO - PROCESS | 4028 | __metrics Screen width/height:1600/1200 08:32:59 INFO - PROCESS | 4028 | colorDepth:24 08:32:59 INFO - PROCESS | 4028 | Browser inner width/height: 1024/697 08:32:59 INFO - PROCESS | 4028 | __metrics 08:33:00 INFO - PROCESS | 4028 | 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" 08:33:00 INFO - PROCESS | 4028 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:33:01 INFO - TEST-INFO | 4028: exit 0 08:33:01 INFO - Browser initialized. 08:33:01 INFO - Running cycle 1/1 for dromaeo_css test... 08:33:01 INFO - TEST-INFO | started process 4109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEw4RDD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:13 INFO - PROCESS | 4109 | RSS: Main: 158953472 08:33:13 INFO - PROCESS | 4109 | 08:34:04 INFO - PROCESS | 4109 | 1468942444110 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] 08:36:16 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45915/tests/dromaeo/cssquery-ext.html) 08:36:18 INFO - PROCESS | 4109 | RSS: Main: 276410368 08:36:18 INFO - PROCESS | 4109 | 08:39:21 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-ext.html (next: http://localhost:45915/tests/dromaeo/cssquery-jquery.html) 08:39:21 INFO - PROCESS | 4109 | RSS: Main: 241065984 08:39:21 INFO - PROCESS | 4109 | 08:42:23 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45915/tests/dromaeo/cssquery-mootools.html) 08:42:24 INFO - PROCESS | 4109 | RSS: Main: 239804416 08:42:24 INFO - PROCESS | 4109 | 08:45:26 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45915/tests/dromaeo/cssquery-prototype.html) 08:45:27 INFO - PROCESS | 4109 | RSS: Main: 237522944 08:45:27 INFO - PROCESS | 4109 | 08:48:30 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45915/tests/dromaeo/cssquery-yui.html) 08:48:30 INFO - PROCESS | 4109 | RSS: Main: 210788352 08:48:30 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-yui.html (next: http://localhost:45915/tests/dromaeo/cssquery-dojo.html) 08:51:33 INFO - PROCESS | 4109 | RSS: Main: 275841024 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | __start_tp_report 08:51:33 INFO - PROCESS | 4109 | _x_x_mozilla_page_load 08:51:33 INFO - PROCESS | 4109 | _x_x_mozilla_page_load_details 08:51:33 INFO - PROCESS | 4109 | |i|pagename|runs| 08:51:33 INFO - PROCESS | 4109 | |0;dojo.html;2607.960880586791;2622.056059818466;2649.562822134348;2659.627652128701;2667.266501712029;4562.270036794113;4657.161710892038;4697.365855609496;4704.4589872164715;4738.810447582093;4688.765561721909;4704.458987216462;4729.834455794048;4764.071006153801;4778.2115950868065;4096.160287141144;4255.723377980436;4383.6273916716955;4400.251957167289;4478.843240486583;4408.25059739845;4435.84474543391;4448.510663826976;4449.399331090293;4477.932831007537;4220.472440944882;4240.024794297329;4276.3157894737005;4277.272863613162;4305.677074219446;4287.035417031174;4299.613034826881;4318.1363727254675;4336.262835318003;4361.542038086019;4320.481542214923;4326.09152078064;4342.066455712005;4366.061296821167;4381.101874115799;4512.684112112153;4608.861734147981;4646.163690535702;4710.505396933307;4741.19399702048;5051.747412629354;5078.46676099008;5119.180931051052;5121.206213036985;5130.256112863613;5112.744362781883;5140.820071297487;5141.228815677641;5144.639875208737;5148.588112950955;4598.448186217642;4648;4656.790444430008;4671.229247174178;4672.392588963413;5151.8196863109615;5159.819406320836;5169.095408303532;5175.042617115846;5176;4738.976305118521;4913.508649135129;4915.631327650441;4956.653034287638;4968.925466118011;4907.043126590352;5045.11710450677;5064.063148317573;5065.848024559269;5069.467705890828;5000.324956130876;5011.523905228997;5012.674176178537;5016.946441247306;5062.772175252123;4782.713037217778;4845.491223421561;4977.65156439053;4994.850154495371;5001.074801161797;4206.8527601534415;4213.220554197484;4227.3447615619625;4256.702030857872;4322.8703138905885;3703.1679444524466;3709.1283548366114;3709.221063576625;3741.5323084614424;3754.9249015019545;4312.5903039211225;4438.6005259526155;4439.334099885043;4483.686141970031;4485.237509623372;5030.874228144253;5076.035553244872;5104.387473503203;5107.540321371094;5114.0539000284325;4447.399601053817;4467.932981005288;4474.753888536162;4475.843345482893;4513.255312873404;4406.669499787528;4502.392177056121;4598.517155698671;4598.678092533558;4638.582527572535;4751.382320298339;4781.952180478151;4803.351547541037;4803.639727020418;4855.587275081658;5068.189089745623;5075.77159027852;5079.034983353151;5081.339425874613;5116.027954688598;4612.630989520897;4743.881402964954;4776.95223047779;4801.231802911518;4833.178359678934;4980.078685443204;4998.900021999467;5002.224655178453;5002.399712034579;5034.622403319692;4998.60011199096;5037.168867136881;5071.391433027913;5073.746312684277;5104.464031276811;4684.6486513510945;4872.171730805842;4887.584555312838;4930.112579735682;4959.677620954626;4934.087193869145;4941.678790878582;4954.355933728592;4957.107720610325;5006.224035274538;4873.171560834738;4874.610031197567;4882.340883980617;4924.187509060965;4943.986482771095;4747.074320507468;4770.642201834807;4784.449788274393;4815.30178124163;4836.226203807374;4598.609118224855;4610.654200934876;4613.054323863667;4614.561616646413;4656.464506581651 08:51:33 INFO - PROCESS | 4109 | |1;ext.html;542.8013755925272;585.8447511409474;587.4566026425555;589.3958692340353;595.1905408644244;39559;41221.17557648845;41244;41262;44844.775776121154;40830.18339633205;41357.93210339486;41374.58625413745;41375.379369309485;41460.58539414605;7769.7669069928;7774.144844067162;7932.801679957989;7942.761717148494;7945.364370850347;7689.192634773324;10038.498075096253;10327.586896524168;10337.27639065262;10698.732531686692;10309.587616495332;10325.019123183285;10329.380237185793;10350.741231469197;10359.533820978037;7673.386129109658;7805.921940780633;10280.074793268604;10327.741806454898;10332.896671033268;7734.7679569613;10086.09225169731;10475.947620261926;10500.842487362695;10519.579216831318;37013.62986370129;37125.25749485022;37397.43903841444;40422.797886010674;40474.39288410676;3162.5572419861237;3436.5876094868527;3563.5367402237803;3566.4472006838723;3757.6993840492696;29906.85046574775;30186.094417167522;30343.69656303453;30589.38821223563;31644.683553164636;3403.261085702913;3418.8974330770116;3426.5202871597994;3459.930801383958;3485.843137058813;255257;258628;259115;260084;260645.9999999981;56675;56992.71503642455;57101.428985710445;57170.42829571651;57238.7138064307;58989.70505147447;59291;59402.10896836551;59536.404635953084;59611.21166365016;14482.637934051732;14891;14894.106353618601;14909;14909.030912990622;10264.281500294908;11387.08903287735;11552.018078463258;11634.127440441831;11638.010769084553;18923.43229703111;18941.147648355858;18965.43103706891;19132.61734765297;19133.13900874464;6846.691898864563;6905.792826215121;6936.237013928464;7005.754798582026;7286;799.5242830515833;877.0265005843509;886.1847100667272;894.5840184314195;989.7129832348683;27669.86165069202;28080.29799255035;28207.43585128327;28493.715062849522;28704.856475717486;921.893982192043;950.5769932380163;964.9179819715263;991.2530634202582;1028.6245520385005;14008.789868151986;14493;14576.635584110481;14674.486392976194;14722.484713034992;374508;377212;378438;380182;380274;48305.75847120742;48745.756271218415;48749.26876096861;48810.26784598234;48892;1115.3865374044244;1133.9886601134212;1181.1790805390515;1203.8194270859442;1258.779713550125;26178.214653560422;28537.714622853506;28564;29184.41631167407;29408.11775646734;39974.2005159901;40235.597644024354;40761;40761.79619101885;40792.79603601963;254924;256789;256850;256954;257056;22390.888045559666;24794.02471703407;25125.87437062803;25362.619560706236;25410.618840717772;40323.3951490722;40409.1918161641;40828.59171408248;40852;41247.381289281286;34979.72558466642;35171.29657406889;35573.466398004566;35694;36000.27999439944;35506.822465887504;35643.64356356507;36005.45991810178;36114.8194259027;36247.63752362443;34815.82592087024;35054.29891402209;35324.64675353316;36443.45334819927;37109.257814844095;31662.525062124547;33042.84640690369;33067.50398744069;33566.328673426884;33631.831840841616;25347.611153427297;25763.871180644728;26130.73869261283;26247;26283.474330513665 08:51:33 INFO - PROCESS | 4109 | |2;jquery.html;342.8594276346698;364.8011833550715;367.3039589976993;369.0681030398244;392.6917369864657;13108;16525.586860328498;16923.830761692378;18022.639547209048;18062.27750889966;15897.602559935978;16003.439879604217;17633;17695.115244237768;17701.291948322083;4537.614302784267;5123.052235336465;5304.920426193591;5309.30978972733;5316.787328506855;5186.429492755813;5251.159814429692;5262.342207224097;5263.973680131613;5271.604629652791;5228.2680424740565;5298.178782288732;5591.3569939457075;5597.468240517144;5609.915851262234;5340.572754179656;5488.341399032101;5536.833894983157;5547.195656629807;5551.639143455683;5273.630845840793;5317.255584218213;5321.2284218788445;5323.893522129574;5329.2006199070065;12089.214201076902;12369.567065152676;12416.317102559356;17897;20202.595948080954;1970.881747095179;2002.2491565662876;2040.6326861164985;2382.6187809950325;2443.902243910259;8893.377463577552;9035.277177825757;9335.486548239844;9487.193588544982;9962.551685174185;1737.5829800847705;1806.2323512507132;1899.943001709951;1941.1264930781065;1945.105251584273;69607;70276;70408.6479567604;71668.64165679138;71810.64094679493;8753.518556479328;9268.119528644767;9385.859212111687;9436.528173591292;10421.322614030063;12553.497860085678;13341.533046343331;13352.532661356807;13766.380512876945;13780.173189608455;7272.63636818157;7336.156342020736;7336.816579585553;7341.853162936712;7421.4804963653105;6941.409980151744;7187.281271872876;7213.422926165999;7220.133583969852;7326.780196594111;4314.374415709704;4350.499692535399;4431.82272709088;4468.3074123510905;4471.083427897273;1781.2963879267875;1815.7821061472443;1844.7509586205958;1845.188117228442;1879.7650293713286;831.3100126894784;868.431177578687;886.964521419136;924.6994726713845;935.2938531408743;16395;16999.394438763833;17277.13614319279;17430.738538921927;17463.650726985645;822.6170123144847;853.7993571510814;883;903.8282726281985;910.9954450227707;9047.638094476148;9159.404638698463;9576.186024188022;10006.349587276804;10028.498575071217;427924;432109;432649;435912;442694;74659;81272;84693;85576;87295.1270487287;922.2299380017627;957.30595318395;957.7318350861769;973.756560859785;974.936629119105;15880.444184453489;16105;16220.51338459848;16312.265948032129;16345.836541634431;35698.8215058923;36653.26693466169;37643.435348470346;39650;42425;53739.46260537501;54126.72936635291;54731.45268547423;54938.45061549333;55191;7261.201267860425;7567.432442566939;7594.924050759642;7781.610919453891;7840;10058;11746;11858.288502689866;11986.70033249141;12161.45273462714;9739.269554783277;9784;10006.14947729422;10126.189904807745;11087.223894327642;10451.843222351823;10502.632407865687;10597.364158150536;10802.621908233174;10925.016748492673;10339.276250662382;10697.304675196086;10731;10960;11178.664640060812;7587.2412758723685;7715.344195743424;7725.690972361042;7819.257170568787;7911.367090632621;9472.431654100776;10296.330738501973;10396.792064158522;10530.210234232616;10931.18016148776 08:51:33 INFO - PROCESS | 4109 | |3;mootools.html;1235.96910077248;1384.6815232496526;1427.8929080318976;1537.3773621683215;1747.3884140550801;6711.597304161747;7556.508826926252;7779.2998630123275;7787.4938129021575;7990.440669153161;7695.192004839495;7844.568548729818;7848.332891704212;7850.725224617139;7862.606869656523;2190.759016508179;2218.090582861027;2220.4448887778058;2221.688963545105;2222.377734234417;2175.8476906616543;2188.8248940084836;2232.3191426614876;2238.29493709481;2238.6865838782583;2195.8243340532717;2197.263916587937;2207.547452772186;2209.028027667829;2229.3423440085135;2158.719366482355;2164.935051948444;2165.9133634654595;2167.1873047607146;2168.5229249565073;2147.806697397242;2182.0726191368603;2184.3010236724253;2185.694002839604;2188.2122435923216;7714.807129821743;7829.569373684445;7839.1768864269;7890.8816367754525;7961.084475285301;4624.306354046887;4716.339712440261;4738.64460165489;4746.477887432379;4751.4535828379485;2813.7186281371905;2942.823430594171;2973.8810447582073;2977.568252603382;2983.4033193361197;970.8932017478072;971.455984648592;984.9409035457824;987.8172538080478;987.9259055570789;10610.363378197255;10713.946430267877;10719.571217151304;10725.839112413398;10756.139508839275;4109.815058322382;4151.878992671962;4174.540800511942;4200.096979149497;4228.408022876738;8402.369822263354;8506.447080939843;8507.191816777397;8515.57422128891;8515.787105322293;4667.229907065296;4669.0428462165855;4671;4671.089137618132;4672.8551504881525;3714.4985426967555;3816.561095474056;4234;4347.673924455678;4352.7823608819435;3517.613062563167;3543.6279190685123;3588.4617307404096;3607.2424790793702;3723.906902327409;1048.6067724603274;1059.915206783471;1069.9946500267604;1073.066432203972;1074.4037059431935;706.9646517674096;855.255927343203;893.5889490834289;902.3863772634671;942.6182396129578;5422.566194704415;5627.071533196977;5686.09022556389;5702.51528620072;5774.682392468455;879.3185281406961;911.6307895302413;941.2187884056217;951.981379923489;977.1498796047484;3650.762700424464;3742.2702572998546;3748.194138260287;3768.7563104176106;3776.0937375029794;9165.266778657824;9486.715398538056;9516.143547080657;9530;9770.36492627977;9601.423914565148;9636;9636.42181469114;9649.614015439443;9684.612615495442;7038.3665470107935;7495.287947644965;7499.400047996147;7514.887276690854;7521.435892307989;5442.727863606883;5578.63738856973;5669.574781891292;5680.431956804452;5754.280714910637;8730.745409865563;8863.689770857989;9068.047854975397;9117.723518707253;9170.358074934957;8652.913470865462;8989.370744047854;9219.354645174773;9267.119623635745;9328.486933218737;6844;6974.37230649244;6996.965015174892;7020.0522929405915;7030.050943122613;7524.510906791041;7703.422243331885;7724.575148366894;7741.071071471234;7745.457817952689;6874.106366172366;6955.860882782417;7015.298470152944;7108.182559005781;7185.137783466018;6256.374362563707;6393.4885209184085;6437.969924812008;6679.532432729857;6688.765893193715;6498.480121590356;6531.477481801539;6571.638559879252;6574.704138313692;6585.901211481927;4502;4697.600703940203;4703.223968045371;4784.832530861403;4811.109944660249;4758.405199350082;4803.063402636452;4837.782299796448;4864.051509955525;4887.951120488892 08:51:33 INFO - PROCESS | 4109 | |4;prototype.html;291.78407978096214;300.34524736075355;301.5175718849839;303.55074489755145;308.2571003880647;4773.546513081256;4781.904361912764;4785.784639691214;4792.472827988919;4832.4684284728655;4715.292706094087;4717.69334993226;4746.596539294164;4789.640776941726;4792.90414191716;3552.538170037898;3675.1363429594026;3702.61122582129;3771.057235691077;3802.9809850950846;3728.2170744143764;3764.4729737836724;3773.584905660375;3785.5646600640866;3804.2962052020466;3728.639046747931;3728.850845966158;3740.831662575191;3749.7000239980734;3752.5309336332957;3547.5920269168987;3630.382834918057;3743.3636281832155;3756.9624303756887;3776.811159442017;3326.8170250636203;3343.3480471308103;3350.564426624523;3354.3626710924846;3362.6301106878223;4626.756023328683;4638.327442520816;4650.558196971282;4661.370714953506;4670.789814458358;4178.35235538489;4249.192653395845;4259;4266.080263881266;4279.443672322609;4851.56335929768;4899.314096026559;4915.311856340079;4915.729634870081;4927.993923706536;3592.7485076044686;3596.4065929121925;3597.442396428558;3609.5668519777796;3635.6546128118052;4906.656534042583;5203.98522288154;5223.347081614798;5228.660137091077;5232.215167724834;3572.214112895159;3587.551556055493;3587.551556055493;3596.6403359663823;3606.9819650901577;3646.908827279339;3669.6697297243377;3675.9264814703556;3689.8893033209047;3693.298273328059;4158.958410415918;4197.727147735387;4214.957850421457;4220;4232.936505952413;4785.832495862628;4935.210366341368;4953.578945789648;4953.950460495421;4956.727379994135;1252.843394575678;1254.5948537637903;1254.6637830710265;1262.9706788966962;1264.3804535777588;788.9408294377945;803.5419810707957;804.5856383962264;807.3097501636134;822.8189798244376;4390.582894625006;4454.131444368382;4470.798814053375;4482.686211965131;4513.480949690762;5096.108181068373;5097.617678674114;5123.308353372247;5139.537441630271;5140.434552199255;4490.753008584494;4492.326151077365;4492.528284530077;4495.483019452739;4518.480374756879;2570.7043689975894;2742.3555464465835;2744.698083210845;2745.3136715821047;2747.587861820743;4880;4894.437139728905;4914.483979182134;4925.310456536088;4932.926006109911;5030.346055012825;5291.920621190685;5301.602379821529;5318.308619879391;5328.946710532922;4886.92669609949;4905.3132561441425;4917.754112294371;4923.212286034217;4926.310316555685;5146.382434107931;5153.845384638467;5157.381114266312;5168.638195326291;5197.324347834758;4859.566427903847;5020.146575082172;5033.697978121324;5046.6719663221775;5055.216441451581;4893.584045356185;4901.681390709735;4913.533214344775;4915.705057696549;4922.4339200992345;3576;3599.226166374242;3600.4599310103695;3601.369760291886;3610.693091087287;4891.217405215149;5155.252488389237;5159.690418574897;5168.689878607295;5198.584113271005;4930.063287975273;4962.833734072562;4969.552740253394;4985.900281994413;5033.899322013613;4987.80292729735;4988.625853061108;4995.175795993766;5059.886824898516;5061.4432412435335;4765.9523404765505;4886.780094895809;4890.486498917562;4910.165271903857;4931.654784165074;4748.738819364969;4854.344663470528;4966.602671786321;4967.230079337708;4970.570306488656;4915.926261106015;4929.630277729113;4933.260010998379;4952.257161425671;4956.306117143675 08:51:33 INFO - PROCESS | 4109 | |5;yui.html;611.2909025530384;646.6766616691657;662.4811851382397;732.6153769271135;746.7423738810108;12509.562165324216;13637.522686705955;13648.249346285951;13665.931670341646;13672.658183545416;12339.753204935896;12776.233425994447;12945.805812912813;12988.870111298884;13021.088523803337;781.2187812187813;791.9920800791976;792.9484583502083;793.4683761879526;796.4942261663828;627.4509803921569;755.7921571567808;776.7436745873877;782.0810547606568;783.682608543538;833.1293797981094;835.340081335744;835.7075023741672;838.5094719589048;839.9160083991583;831.2850948184556;833.7998880268752;834.0825092398373;834.170000849159;834.407570624851;473.69210013491164;482.3945947835444;483.5696230354987;484.98302559410604;590.7194082810673;11863.228890122115;12285.447154878051;12321.322327271997;12334.629961101182;12357.25856448616;11629.883701162964;12188.390580470941;12226.694332641666;12389.946854517377;12563.874361256454;11652.417379131095;11732.17874748768;11797.823032654516;11942.223755455867;12413.255204687748;3948.4077388391684;4002.8398864045403;4027.597240275978;4045.702752256901;4125.731827431207;60448.395516044715;61744.382556174314;61759.99999999955;61953.69023154901;62423.375766242214;9558.617655293849;9698.660546880967;10159.390436573682;10329.638462653771;10358.171346292278;16524.25640846165;17894.5526361842;17975.1012449377;17988.280468781362;18019.18913648889;6426.196725409324;6626.9337306627285;6642.767503137367;6782.423494003065;6810.284920083419;3614.367485689994;3646.5988741238443;3667.101560117735;3679.3745063339297;3720.051386896324;2767.0868613357543;2840.801143919947;2868.3689588290545;2869.4404591104535;2869.497837878363;493.50156342094743;499.3059647090547;503.94456609772897;507.9669821461593;509.41417370024766;475.40099474662173;562.5893098038455;568.0967262053355;578.9710514474343;588.1295682390087;7232.602206878672;7285.526440781332;7636.579988100597;7866.646000929972;7891.802704932308;526.9683818970874;553.4078535966477;555.9555235581224;569.6667449541982;602.1690067706625;3523;3578.248567800791;3586.0317714217017;3621.6016238213774;3638.508801311789;51347.22979155316;91662;92010.53994729984;93384;93849.5307523458;82002.58998704968;83919.58040209759;84629;85630;85849.0848934964;48222;70297.29702703112;71669;73274.26725732781;76251;6619.50353723463;6876.209235937731;6898.655067246718;6938.826529336605;7039.366457018893;31532.684673152973;37777;44135.33796993112;44782.3282650767;45574.08851822879;56437.43562564486;56794.43205567892;62099.6895015522;67051.66474167598;68130;8906.777330566787;8960.865587016331;9088.045755195873;9324;9346.672866449913;10269.281150319704;10548.367097974146;10763.246572739834;11990.40047997614;12378.75242495127;12133.332666703416;12176.695582610506;12860.356982150668;12868.806967895676;12985.28580928058;7427.96286018588;11740.002099821611;12224.205426647595;12372.257664540157;13091.149075310075;10778.838317425401;10822.296550724177;10864.891351086704;11097.278676885977;11468.655940321803;10500.527476263434;10606.734831628963;10702.785944281226;10918.12654987615;10937.726556836144;9901.950490247791;10122.342047766871;10135.442550659489;10145.239107067147;10509.211809114195 08:51:33 INFO - PROCESS | 4109 | __end_tp_report 08:51:33 INFO - PROCESS | 4109 | __start_cc_report 08:51:33 INFO - PROCESS | 4109 | _x_x_mozilla_cycle_collect,1146 08:51:33 INFO - PROCESS | 4109 | __end_cc_report 08:51:33 INFO - PROCESS | 4109 | __startTimestamp1468943493804__endTimestamp 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | ------- Summary: start ------- 08:51:33 INFO - PROCESS | 4109 | Number of tests: 6 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#0] dojo.html Cycles:165 Average:4656.27 Median:4747.07 stddev:487.38 (10.3%) stddev-sans-first:461.63 08:51:33 INFO - PROCESS | 4109 | Values: 2608.0 2622.1 2649.6 2659.6 2667.3 4562.3 4657.2 4697.4 4704.5 4738.8 4688.8 4704.5 4729.8 4764.1 4778.2 4096.2 4255.7 4383.6 4400.3 4478.8 4408.3 4435.8 4448.5 4449.4 4477.9 4220.5 4240.0 4276.3 4277.3 4305.7 4287.0 4299.6 4318.1 4336.3 4361.5 4320.5 4326.1 4342.1 4366.1 4381.1 4512.7 4608.9 4646.2 4710.5 4741.2 5051.7 5078.5 5119.2 5121.2 5130.3 5112.7 5140.8 5141.2 5144.6 5148.6 4598.4 4648.0 4656.8 4671.2 4672.4 5151.8 5159.8 5169.1 5175.0 5176.0 4739.0 4913.5 4915.6 4956.7 4968.9 4907.0 5045.1 5064.1 5065.8 5069.5 5000.3 5011.5 5012.7 5016.9 5062.8 4782.7 4845.5 4977.7 4994.9 5001.1 4206.9 4213.2 4227.3 4256.7 4322.9 3703.2 3709.1 3709.2 3741.5 3754.9 4312.6 4438.6 4439.3 4483.7 4485.2 5030.9 5076.0 5104.4 5107.5 5114.1 4447.4 4467.9 4474.8 4475.8 4513.3 4406.7 4502.4 4598.5 4598.7 4638.6 4751.4 4782.0 4803.4 4803.6 4855.6 5068.2 5075.8 5079.0 5081.3 5116.0 4612.6 4743.9 4777.0 4801.2 4833.2 4980.1 4998.9 5002.2 5002.4 5034.6 4998.6 5037.2 5071.4 5073.7 5104.5 4684.6 4872.2 4887.6 4930.1 4959.7 4934.1 4941.7 4954.4 4957.1 5006.2 4873.2 4874.6 4882.3 4924.2 4944.0 4747.1 4770.6 4784.4 4815.3 4836.2 4598.6 4610.7 4613.1 4614.6 4656.5 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#1] ext.html Cycles:180 Average:46203.05 Median:35017.01 stddev:79530.72 (227.1%) stddev-sans-first:79679.93 08:51:33 INFO - PROCESS | 4109 | Values: 542.8 585.8 587.5 589.4 595.2 39559.0 41221.2 41244.0 41262.0 44844.8 40830.2 41357.9 41374.6 41375.4 41460.6 7769.8 7774.1 7932.8 7942.8 7945.4 7689.2 10038.5 10327.6 10337.3 10698.7 10309.6 10325.0 10329.4 10350.7 10359.5 7673.4 7805.9 10280.1 10327.7 10332.9 7734.8 10086.1 10475.9 10500.8 10519.6 37013.6 37125.3 37397.4 40422.8 40474.4 3162.6 3436.6 3563.5 3566.4 3757.7 29906.9 30186.1 30343.7 30589.4 31644.7 3403.3 3418.9 3426.5 3459.9 3485.8 255257.0 258628.0 259115.0 260084.0 260646.0 56675.0 56992.7 57101.4 57170.4 57238.7 58989.7 59291.0 59402.1 59536.4 59611.2 14482.6 14891.0 14894.1 14909.0 14909.0 10264.3 11387.1 11552.0 11634.1 11638.0 18923.4 18941.1 18965.4 19132.6 19133.1 6846.7 6905.8 6936.2 7005.8 7286.0 799.5 877.0 886.2 894.6 989.7 27669.9 28080.3 28207.4 28493.7 28704.9 921.9 950.6 964.9 991.3 1028.6 14008.8 14493.0 14576.6 14674.5 14722.5 374508.0 377212.0 378438.0 380182.0 380274.0 48305.8 48745.8 48749.3 48810.3 48892.0 1115.4 1134.0 1181.2 1203.8 1258.8 26178.2 28537.7 28564.0 29184.4 29408.1 39974.2 40235.6 40761.0 40761.8 40792.8 254924.0 256789.0 256850.0 256954.0 257056.0 22390.9 24794.0 25125.9 25362.6 25410.6 40323.4 40409.2 40828.6 40852.0 41247.4 34979.7 35171.3 35573.5 35694.0 36000.3 35506.8 35643.6 36005.5 36114.8 36247.6 34815.8 35054.3 35324.6 36443.5 37109.3 31662.5 33042.8 33067.5 33566.3 33631.8 25347.6 25763.9 26130.7 26247.0 26283.5 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#2] jquery.html Cycles:180 Average:25764.93 Median:4830.33 stddev:71653.73 (1483.4%) stddev-sans-first:71829.31 08:51:33 INFO - PROCESS | 4109 | Values: 342.9 364.8 367.3 369.1 392.7 13108.0 16525.6 16923.8 18022.6 18062.3 15897.6 16003.4 17633.0 17695.1 17701.3 4537.6 5123.1 5304.9 5309.3 5316.8 5186.4 5251.2 5262.3 5264.0 5271.6 5228.3 5298.2 5591.4 5597.5 5609.9 5340.6 5488.3 5536.8 5547.2 5551.6 5273.6 5317.3 5321.2 5323.9 5329.2 12089.2 12369.6 12416.3 17897.0 20202.6 1970.9 2002.2 2040.6 2382.6 2443.9 8893.4 9035.3 9335.5 9487.2 9962.6 1737.6 1806.2 1899.9 1941.1 1945.1 69607.0 70276.0 70408.6 71668.6 71810.6 8753.5 9268.1 9385.9 9436.5 10421.3 12553.5 13341.5 13352.5 13766.4 13780.2 7272.6 7336.2 7336.8 7341.9 7421.5 6941.4 7187.3 7213.4 7220.1 7326.8 4314.4 4350.5 4431.8 4468.3 4471.1 1781.3 1815.8 1844.8 1845.2 1879.8 831.3 868.4 887.0 924.7 935.3 16395.0 16999.4 17277.1 17430.7 17463.7 822.6 853.8 883.0 903.8 911.0 9047.6 9159.4 9576.2 10006.3 10028.5 427924.0 432109.0 432649.0 435912.0 442694.0 74659.0 81272.0 84693.0 85576.0 87295.1 922.2 957.3 957.7 973.8 974.9 15880.4 16105.0 16220.5 16312.3 16345.8 35698.8 36653.3 37643.4 39650.0 42425.0 53739.5 54126.7 54731.5 54938.5 55191.0 7261.2 7567.4 7594.9 7781.6 7840.0 10058.0 11746.0 11858.3 11986.7 12161.5 9739.3 9784.0 10006.1 10126.2 11087.2 10451.8 10502.6 10597.4 10802.6 10925.0 10339.3 10697.3 10731.0 10960.0 11178.7 7587.2 7715.3 7725.7 7819.3 7911.4 9472.4 10296.3 10396.8 10530.2 10931.2 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#3] mootools.html Cycles:180 Average:5188.78 Median:5683.26 stddev:2867.30 (50.5%) stddev-sans-first:2859.95 08:51:33 INFO - PROCESS | 4109 | Values: 1236.0 1384.7 1427.9 1537.4 1747.4 6711.6 7556.5 7779.3 7787.5 7990.4 7695.2 7844.6 7848.3 7850.7 7862.6 2190.8 2218.1 2220.4 2221.7 2222.4 2175.8 2188.8 2232.3 2238.3 2238.7 2195.8 2197.3 2207.5 2209.0 2229.3 2158.7 2164.9 2165.9 2167.2 2168.5 2147.8 2182.1 2184.3 2185.7 2188.2 7714.8 7829.6 7839.2 7890.9 7961.1 4624.3 4716.3 4738.6 4746.5 4751.5 2813.7 2942.8 2973.9 2977.6 2983.4 970.9 971.5 984.9 987.8 987.9 10610.4 10713.9 10719.6 10725.8 10756.1 4109.8 4151.9 4174.5 4200.1 4228.4 8402.4 8506.4 8507.2 8515.6 8515.8 4667.2 4669.0 4671.0 4671.1 4672.9 3714.5 3816.6 4234.0 4347.7 4352.8 3517.6 3543.6 3588.5 3607.2 3723.9 1048.6 1059.9 1070.0 1073.1 1074.4 707.0 855.3 893.6 902.4 942.6 5422.6 5627.1 5686.1 5702.5 5774.7 879.3 911.6 941.2 952.0 977.1 3650.8 3742.3 3748.2 3768.8 3776.1 9165.3 9486.7 9516.1 9530.0 9770.4 9601.4 9636.0 9636.4 9649.6 9684.6 7038.4 7495.3 7499.4 7514.9 7521.4 5442.7 5578.6 5669.6 5680.4 5754.3 8730.7 8863.7 9068.0 9117.7 9170.4 8652.9 8989.4 9219.4 9267.1 9328.5 6844.0 6974.4 6997.0 7020.1 7030.1 7524.5 7703.4 7724.6 7741.1 7745.5 6874.1 6955.9 7015.3 7108.2 7185.1 6256.4 6393.5 6438.0 6679.5 6688.8 6498.5 6531.5 6571.6 6574.7 6585.9 4502.0 4697.6 4703.2 4784.8 4811.1 4758.4 4803.1 4837.8 4864.1 4888.0 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#4] prototype.html Cycles:180 Average:4152.14 Median:4777.73 stddev:1207.52 (25.3%) stddev-sans-first:1175.63 08:51:33 INFO - PROCESS | 4109 | Values: 291.8 300.3 301.5 303.6 308.3 4773.5 4781.9 4785.8 4792.5 4832.5 4715.3 4717.7 4746.6 4789.6 4792.9 3552.5 3675.1 3702.6 3771.1 3803.0 3728.2 3764.5 3773.6 3785.6 3804.3 3728.6 3728.9 3740.8 3749.7 3752.5 3547.6 3630.4 3743.4 3757.0 3776.8 3326.8 3343.3 3350.6 3354.4 3362.6 4626.8 4638.3 4650.6 4661.4 4670.8 4178.4 4249.2 4259.0 4266.1 4279.4 4851.6 4899.3 4915.3 4915.7 4928.0 3592.7 3596.4 3597.4 3609.6 3635.7 4906.7 5204.0 5223.3 5228.7 5232.2 3572.2 3587.6 3587.6 3596.6 3607.0 3646.9 3669.7 3675.9 3689.9 3693.3 4159.0 4197.7 4215.0 4220.0 4232.9 4785.8 4935.2 4953.6 4954.0 4956.7 1252.8 1254.6 1254.7 1263.0 1264.4 788.9 803.5 804.6 807.3 822.8 4390.6 4454.1 4470.8 4482.7 4513.5 5096.1 5097.6 5123.3 5139.5 5140.4 4490.8 4492.3 4492.5 4495.5 4518.5 2570.7 2742.4 2744.7 2745.3 2747.6 4880.0 4894.4 4914.5 4925.3 4932.9 5030.3 5291.9 5301.6 5318.3 5328.9 4886.9 4905.3 4917.8 4923.2 4926.3 5146.4 5153.8 5157.4 5168.6 5197.3 4859.6 5020.1 5033.7 5046.7 5055.2 4893.6 4901.7 4913.5 4915.7 4922.4 3576.0 3599.2 3600.5 3601.4 3610.7 4891.2 5155.3 5159.7 5168.7 5198.6 4930.1 4962.8 4969.6 4985.9 5033.9 4987.8 4988.6 4995.2 5059.9 5061.4 4766.0 4886.8 4890.5 4910.2 4931.7 4748.7 4854.3 4966.6 4967.2 4970.6 4915.9 4929.6 4933.3 4952.3 4956.3 08:51:33 INFO - PROCESS | 4109 | 08:51:33 INFO - PROCESS | 4109 | [#5] yui.html Cycles:180 Average:16918.21 Median:484.28 stddev:23967.28 (4949.1%) stddev-sans-first:24003.23 08:51:33 INFO - PROCESS | 4109 | Values: 611.3 646.7 662.5 732.6 746.7 12509.6 13637.5 13648.2 13665.9 13672.7 12339.8 12776.2 12945.8 12988.9 13021.1 781.2 792.0 792.9 793.5 796.5 627.5 755.8 776.7 782.1 783.7 833.1 835.3 835.7 838.5 839.9 831.3 833.8 834.1 834.2 834.4 473.7 482.4 483.6 485.0 590.7 11863.2 12285.4 12321.3 12334.6 12357.3 11629.9 12188.4 12226.7 12389.9 12563.9 11652.4 11732.2 11797.8 11942.2 12413.3 3948.4 4002.8 4027.6 4045.7 4125.7 60448.4 61744.4 61760.0 61953.7 62423.4 9558.6 9698.7 10159.4 10329.6 10358.2 16524.3 17894.6 17975.1 17988.3 18019.2 6426.2 6626.9 6642.8 6782.4 6810.3 3614.4 3646.6 3667.1 3679.4 3720.1 2767.1 2840.8 2868.4 2869.4 2869.5 493.5 499.3 503.9 508.0 509.4 475.4 562.6 568.1 579.0 588.1 7232.6 7285.5 7636.6 7866.6 7891.8 527.0 553.4 556.0 569.7 602.2 3523.0 3578.2 3586.0 3621.6 3638.5 51347.2 91662.0 92010.5 93384.0 93849.5 82002.6 83919.6 84629.0 85630.0 85849.1 48222.0 70297.3 71669.0 73274.3 76251.0 6619.5 6876.2 6898.7 6938.8 7039.4 31532.7 37777.0 44135.3 44782.3 45574.1 56437.4 56794.4 62099.7 67051.7 68130.0 8906.8 8960.9 9088.0 9324.0 9346.7 10269.3 10548.4 10763.2 11990.4 12378.8 12133.3 12176.7 12860.4 12868.8 12985.3 7428.0 11740.0 12224.2 12372.3 13091.1 10778.8 10822.3 10864.9 11097.3 11468.7 10500.5 10606.7 10702.8 10918.1 10937.7 9902.0 10122.3 10135.4 10145.2 10509.2 08:51:33 INFO - PROCESS | 4109 | -------- Summary: end -------- 08:51:33 INFO - PROCESS | 4109 | 08:51:34 INFO - TEST-INFO | 4109: exit 0 08:51:34 INFO - TEST-OK | dromaeo_css | took 1119642ms 08:51:34 INFO - TEST-START | kraken 08:51:34 INFO - operating with platform_type : linux_ 08:51:34 INFO - Initialising browser for kraken test... 08:51:34 INFO - TEST-INFO | started process 4293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2vmrFr/profile http://localhost:45915/getInfo.html) 08:51:38 INFO - PROCESS | 4293 | __metrics Screen width/height:1600/1200 08:51:38 INFO - PROCESS | 4293 | colorDepth:24 08:51:38 INFO - PROCESS | 4293 | Browser inner width/height: 1024/697 08:51:38 INFO - PROCESS | 4293 | __metrics 08:51:39 INFO - PROCESS | 4293 | 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" 08:51:39 INFO - PROCESS | 4293 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:40 INFO - TEST-INFO | 4293: exit 0 08:51:40 INFO - Browser initialized. 08:51:40 INFO - Running cycle 1/1 for kraken test... 08:51:40 INFO - TEST-INFO | started process 4376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2vmrFr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:52 INFO - PROCESS | 4376 | RSS: Main: 159244288 08:51:52 INFO - PROCESS | 4376 | 08:51:55 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:56 INFO - PROCESS | 4376 | RSS: Main: 218476544 08:51:56 INFO - PROCESS | 4376 | 08:51:59 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-dft) 08:51:59 INFO - PROCESS | 4376 | RSS: Main: 206221312 08:51:59 INFO - PROCESS | 4376 | 08:52:02 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-fft) 08:52:03 INFO - PROCESS | 4376 | RSS: Main: 272547840 08:52:03 INFO - PROCESS | 4376 | 08:52:05 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-oscillator) 08:52:06 INFO - PROCESS | 4376 | RSS: Main: 181874688 08:52:06 INFO - PROCESS | 4376 | 08:52:08 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:09 INFO - PROCESS | 4376 | RSS: Main: 186200064 08:52:09 INFO - PROCESS | 4376 | 08:52:12 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:12 INFO - PROCESS | 4376 | RSS: Main: 222945280 08:52:12 INFO - PROCESS | 4376 | 08:52:16 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-desaturate) 08:52:16 INFO - PROCESS | 4376 | RSS: Main: 217776128 08:52:16 INFO - PROCESS | 4376 | 08:52:19 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45915/tests/kraken/driver.html?testName=json-parse-financial) 08:52:20 INFO - PROCESS | 4376 | RSS: Main: 220459008 08:52:20 INFO - PROCESS | 4376 | 08:52:22 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:22 INFO - PROCESS | 4376 | RSS: Main: 210157568 08:52:22 INFO - PROCESS | 4376 | 08:52:24 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:25 INFO - PROCESS | 4376 | RSS: Main: 214339584 08:52:25 INFO - PROCESS | 4376 | 08:52:27 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:28 INFO - PROCESS | 4376 | RSS: Main: 211705856 08:52:28 INFO - PROCESS | 4376 | 08:52:30 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:31 INFO - PROCESS | 4376 | RSS: Main: 225263616 08:52:31 INFO - PROCESS | 4376 | 08:52:34 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:35 INFO - PROCESS | 4376 | RSS: Main: 223399936 08:52:35 INFO - PROCESS | 4376 | 08:52:36 INFO - PROCESS | 4376 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45915/tests/kraken/driver.html?testName=ai-astar) 08:52:37 INFO - PROCESS | 4376 | RSS: Main: 265527296 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | __start_tp_report 08:52:37 INFO - PROCESS | 4376 | _x_x_mozilla_page_load 08:52:37 INFO - PROCESS | 4376 | _x_x_mozilla_page_load_details 08:52:37 INFO - PROCESS | 4376 | |i|pagename|runs| 08:52:37 INFO - PROCESS | 4376 | |0;ai-astar;103;114;113;114;114;114;113;115;114;117 08:52:37 INFO - PROCESS | 4376 | |1;audio-beat-detection;150;125;124;174;124;123;144;122;160;122 08:52:37 INFO - PROCESS | 4376 | |2;audio-dft;161;161;160;162;161;162;162;161;161;161 08:52:37 INFO - PROCESS | 4376 | |3;audio-fft;76;76;78;107;76;77;75;75;98;77 08:52:37 INFO - PROCESS | 4376 | |4;audio-oscillator;85;85;110;84;85;85;125;85;85;134 08:52:37 INFO - PROCESS | 4376 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;118 08:52:37 INFO - PROCESS | 4376 | |6;imaging-darkroom;147;146;147;147;147;147;146;147;147;146 08:52:37 INFO - PROCESS | 4376 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 08:52:37 INFO - PROCESS | 4376 | |8;json-parse-financial;64;63;61;62;63;71;63;63;62;63 08:52:37 INFO - PROCESS | 4376 | |9;json-stringify-tinderbox;66;65;66;66;67;66;66;66;66;66 08:52:37 INFO - PROCESS | 4376 | |10;stanford-crypto-aes;85;79;78;79;79;80;79;84;77;80 08:52:37 INFO - PROCESS | 4376 | |11;stanford-crypto-ccm;130;130;141;146;137;131;135;146;224;140 08:52:37 INFO - PROCESS | 4376 | |12;stanford-crypto-pbkdf2;167;171;165;165;166;169;170;167;168;164 08:52:37 INFO - PROCESS | 4376 | |13;stanford-crypto-sha256-iterative;67;67;67;66;68;67;73;66;65;66 08:52:37 INFO - PROCESS | 4376 | __end_tp_report 08:52:37 INFO - PROCESS | 4376 | __start_cc_report 08:52:37 INFO - PROCESS | 4376 | _x_x_mozilla_cycle_collect,1902 08:52:37 INFO - PROCESS | 4376 | __end_cc_report 08:52:37 INFO - PROCESS | 4376 | __startTimestamp1468943557018__endTimestamp 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | ------- Summary: start ------- 08:52:37 INFO - PROCESS | 4376 | Number of tests: 14 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.73 (3.3%) stddev-sans-first:1.20 08:52:37 INFO - PROCESS | 4376 | Values: 103.0 114.0 113.0 114.0 114.0 114.0 113.0 115.0 114.0 117.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:134.50 stddev:18.98 (14.1%) stddev-sans-first:19.53 08:52:37 INFO - PROCESS | 4376 | Values: 150.0 125.0 124.0 174.0 124.0 123.0 144.0 122.0 160.0 122.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 08:52:37 INFO - PROCESS | 4376 | Values: 161.0 161.0 160.0 162.0 161.0 162.0 162.0 161.0 161.0 161.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.50 stddev:11.31 (14.8%) stddev-sans-first:11.82 08:52:37 INFO - PROCESS | 4376 | Values: 76.0 76.0 78.0 107.0 76.0 77.0 75.0 75.0 98.0 77.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:85.00 stddev:19.29 (22.7%) stddev-sans-first:20.03 08:52:37 INFO - PROCESS | 4376 | Values: 85.0 85.0 110.0 84.0 85.0 85.0 125.0 85.0 85.0 134.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 08:52:37 INFO - PROCESS | 4376 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 08:52:37 INFO - PROCESS | 4376 | Values: 147.0 146.0 147.0 147.0 147.0 147.0 146.0 147.0 147.0 146.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:52:37 INFO - PROCESS | 4376 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:63.00 stddev:2.76 (4.4%) stddev-sans-first:2.92 08:52:37 INFO - PROCESS | 4376 | Values: 64.0 63.0 61.0 62.0 63.0 71.0 63.0 63.0 62.0 63.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 08:52:37 INFO - PROCESS | 4376 | Values: 66.0 65.0 66.0 66.0 67.0 66.0 66.0 66.0 66.0 66.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:2.54 (3.2%) stddev-sans-first:1.94 08:52:37 INFO - PROCESS | 4376 | Values: 85.0 79.0 78.0 79.0 79.0 80.0 79.0 84.0 77.0 80.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:140.50 stddev:28.06 (20.0%) stddev-sans-first:29.15 08:52:37 INFO - PROCESS | 4376 | Values: 130.0 130.0 141.0 146.0 137.0 131.0 135.0 146.0 224.0 140.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.30 (1.4%) stddev-sans-first:2.44 08:52:37 INFO - PROCESS | 4376 | Values: 167.0 171.0 165.0 165.0 166.0 169.0 170.0 167.0 168.0 164.0 08:52:37 INFO - PROCESS | 4376 | 08:52:37 INFO - PROCESS | 4376 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:2.20 (3.3%) stddev-sans-first:2.33 08:52:37 INFO - PROCESS | 4376 | Values: 67.0 67.0 67.0 66.0 68.0 67.0 73.0 66.0 65.0 66.0 08:52:37 INFO - PROCESS | 4376 | -------- Summary: end -------- 08:52:37 INFO - PROCESS | 4376 | 08:52:38 INFO - TEST-INFO | 4376: exit 0 08:52:38 INFO - TEST-OK | kraken | took 63532ms 08:52:38 INFO - SUITE-END | took 1183s 08:52:38 INFO - Completed test suite (00:19:43) 08:52:38 INFO - javascript benchmark 08:52:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.960880586791, 2622.056059818466, 2649.562822134348, 2659.627652128701, 2667.266501712029, 4562.270036794113, 4657.161710892038, 4697.365855609496, 4704.4589872164715, 4738.810447582093, 4688.765561721909, 4704.458987216462, 4729.834455794048, 4764.071006153801, 4778.2115950868065, 4096.160287141144, 4255.723377980436, 4383.6273916716955, 4400.251957167289, 4478.843240486583, 4408.25059739845, 4435.84474543391, 4448.510663826976, 4449.399331090293, 4477.932831007537, 4220.472440944882, 4240.024794297329, 4276.3157894737005, 4277.272863613162, 4305.677074219446, 4287.035417031174, 4299.613034826881, 4318.1363727254675, 4336.262835318003, 4361.542038086019, 4320.481542214923, 4326.09152078064, 4342.066455712005, 4366.061296821167, 4381.101874115799, 4512.684112112153, 4608.861734147981, 4646.163690535702, 4710.505396933307, 4741.19399702048, 5051.747412629354, 5078.46676099008, 5119.180931051052, 5121.206213036985, 5130.256112863613, 5112.744362781883, 5140.820071297487, 5141.228815677641, 5144.639875208737, 5148.588112950955, 4598.448186217642, 4648.0, 4656.790444430008, 4671.229247174178, 4672.392588963413, 5151.8196863109615, 5159.819406320836, 5169.095408303532, 5175.042617115846, 5176.0, 4738.976305118521, 4913.508649135129, 4915.631327650441, 4956.653034287638, 4968.925466118011, 4907.043126590352, 5045.11710450677, 5064.063148317573, 5065.848024559269, 5069.467705890828, 5000.324956130876, 5011.523905228997, 5012.674176178537, 5016.946441247306, 5062.772175252123, 4782.713037217778, 4845.491223421561, 4977.65156439053, 4994.850154495371, 5001.074801161797, 4206.8527601534415, 4213.220554197484, 4227.3447615619625, 4256.702030857872, 4322.8703138905885, 3703.1679444524466, 3709.1283548366114, 3709.221063576625, 3741.5323084614424, 3754.9249015019545, 4312.5903039211225, 4438.6005259526155, 4439.334099885043, 4483.686141970031, 4485.237509623372, 5030.874228144253, 5076.035553244872, 5104.387473503203, 5107.540321371094, 5114.0539000284325, 4447.399601053817, 4467.932981005288, 4474.753888536162, 4475.843345482893, 4513.255312873404, 4406.669499787528, 4502.392177056121, 4598.517155698671, 4598.678092533558, 4638.582527572535, 4751.382320298339, 4781.952180478151, 4803.351547541037, 4803.639727020418, 4855.587275081658, 5068.189089745623, 5075.77159027852, 5079.034983353151, 5081.339425874613, 5116.027954688598, 4612.630989520897, 4743.881402964954, 4776.95223047779, 4801.231802911518, 4833.178359678934, 4980.078685443204, 4998.900021999467, 5002.224655178453, 5002.399712034579, 5034.622403319692, 4998.60011199096, 5037.168867136881, 5071.391433027913, 5073.746312684277, 5104.464031276811, 4684.6486513510945, 4872.171730805842, 4887.584555312838, 4930.112579735682, 4959.677620954626, 4934.087193869145, 4941.678790878582, 4954.355933728592, 4957.107720610325, 5006.224035274538, 4873.171560834738, 4874.610031197567, 4882.340883980617, 4924.187509060965, 4943.986482771095, 4747.074320507468, 4770.642201834807, 4784.449788274393, 4815.30178124163, 4836.226203807374, 4598.609118224855, 4610.654200934876, 4613.054323863667, 4614.561616646413, 4656.464506581651], "value": 4625.365180452267, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.8013755925272, 585.8447511409474, 587.4566026425555, 589.3958692340353, 595.1905408644244, 39559.0, 41221.17557648845, 41244.0, 41262.0, 44844.775776121154, 40830.18339633205, 41357.93210339486, 41374.58625413745, 41375.379369309485, 41460.58539414605, 7769.7669069928, 7774.144844067162, 7932.801679957989, 7942.761717148494, 7945.364370850347, 7689.192634773324, 10038.498075096253, 10327.586896524168, 10337.27639065262, 10698.732531686692, 10309.587616495332, 10325.019123183285, 10329.380237185793, 10350.741231469197, 10359.533820978037, 7673.386129109658, 7805.921940780633, 10280.074793268604, 10327.741806454898, 10332.896671033268, 7734.7679569613, 10086.09225169731, 10475.947620261926, 10500.842487362695, 10519.579216831318, 37013.62986370129, 37125.25749485022, 37397.43903841444, 40422.797886010674, 40474.39288410676, 3162.5572419861237, 3436.5876094868527, 3563.5367402237803, 3566.4472006838723, 3757.6993840492696, 29906.85046574775, 30186.094417167522, 30343.69656303453, 30589.38821223563, 31644.683553164636, 3403.261085702913, 3418.8974330770116, 3426.5202871597994, 3459.930801383958, 3485.843137058813, 255257.0, 258628.0, 259115.0, 260084.0, 260645.9999999981, 56675.0, 56992.71503642455, 57101.428985710445, 57170.42829571651, 57238.7138064307, 58989.70505147447, 59291.0, 59402.10896836551, 59536.404635953084, 59611.21166365016, 14482.637934051732, 14891.0, 14894.106353618601, 14909.0, 14909.030912990622, 10264.281500294908, 11387.08903287735, 11552.018078463258, 11634.127440441831, 11638.010769084553, 18923.43229703111, 18941.147648355858, 18965.43103706891, 19132.61734765297, 19133.13900874464, 6846.691898864563, 6905.792826215121, 6936.237013928464, 7005.754798582026, 7286.0, 799.5242830515833, 877.0265005843509, 886.1847100667272, 894.5840184314195, 989.7129832348683, 27669.86165069202, 28080.29799255035, 28207.43585128327, 28493.715062849522, 28704.856475717486, 921.893982192043, 950.5769932380163, 964.9179819715263, 991.2530634202582, 1028.6245520385005, 14008.789868151986, 14493.0, 14576.635584110481, 14674.486392976194, 14722.484713034992, 374508.0, 377212.0, 378438.0, 380182.0, 380274.0, 48305.75847120742, 48745.756271218415, 48749.26876096861, 48810.26784598234, 48892.0, 1115.3865374044244, 1133.9886601134212, 1181.1790805390515, 1203.8194270859442, 1258.779713550125, 26178.214653560422, 28537.714622853506, 28564.0, 29184.41631167407, 29408.11775646734, 39974.2005159901, 40235.597644024354, 40761.0, 40761.79619101885, 40792.79603601963, 254924.0, 256789.0, 256850.0, 256954.0, 257056.0, 22390.888045559666, 24794.02471703407, 25125.87437062803, 25362.619560706236, 25410.618840717772, 40323.3951490722, 40409.1918161641, 40828.59171408248, 40852.0, 41247.381289281286, 34979.72558466642, 35171.29657406889, 35573.466398004566, 35694.0, 36000.27999439944, 35506.822465887504, 35643.64356356507, 36005.45991810178, 36114.8194259027, 36247.63752362443, 34815.82592087024, 35054.29891402209, 35324.64675353316, 36443.45334819927, 37109.257814844095, 31662.525062124547, 33042.84640690369, 33067.50398744069, 33566.328673426884, 33631.831840841616, 25347.611153427297, 25763.871180644728, 26130.73869261283, 26247.0, 26283.474330513665], "value": 18072.0647733259, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.8594276346698, 364.8011833550715, 367.3039589976993, 369.0681030398244, 392.6917369864657, 13108.0, 16525.586860328498, 16923.830761692378, 18022.639547209048, 18062.27750889966, 15897.602559935978, 16003.439879604217, 17633.0, 17695.115244237768, 17701.291948322083, 4537.614302784267, 5123.052235336465, 5304.920426193591, 5309.30978972733, 5316.787328506855, 5186.429492755813, 5251.159814429692, 5262.342207224097, 5263.973680131613, 5271.604629652791, 5228.2680424740565, 5298.178782288732, 5591.3569939457075, 5597.468240517144, 5609.915851262234, 5340.572754179656, 5488.341399032101, 5536.833894983157, 5547.195656629807, 5551.639143455683, 5273.630845840793, 5317.255584218213, 5321.2284218788445, 5323.893522129574, 5329.2006199070065, 12089.214201076902, 12369.567065152676, 12416.317102559356, 17897.0, 20202.595948080954, 1970.881747095179, 2002.2491565662876, 2040.6326861164985, 2382.6187809950325, 2443.902243910259, 8893.377463577552, 9035.277177825757, 9335.486548239844, 9487.193588544982, 9962.551685174185, 1737.5829800847705, 1806.2323512507132, 1899.943001709951, 1941.1264930781065, 1945.105251584273, 69607.0, 70276.0, 70408.6479567604, 71668.64165679138, 71810.64094679493, 8753.518556479328, 9268.119528644767, 9385.859212111687, 9436.528173591292, 10421.322614030063, 12553.497860085678, 13341.533046343331, 13352.532661356807, 13766.380512876945, 13780.173189608455, 7272.63636818157, 7336.156342020736, 7336.816579585553, 7341.853162936712, 7421.4804963653105, 6941.409980151744, 7187.281271872876, 7213.422926165999, 7220.133583969852, 7326.780196594111, 4314.374415709704, 4350.499692535399, 4431.82272709088, 4468.3074123510905, 4471.083427897273, 1781.2963879267875, 1815.7821061472443, 1844.7509586205958, 1845.188117228442, 1879.7650293713286, 831.3100126894784, 868.431177578687, 886.964521419136, 924.6994726713845, 935.2938531408743, 16395.0, 16999.394438763833, 17277.13614319279, 17430.738538921927, 17463.650726985645, 822.6170123144847, 853.7993571510814, 883.0, 903.8282726281985, 910.9954450227707, 9047.638094476148, 9159.404638698463, 9576.186024188022, 10006.349587276804, 10028.498575071217, 427924.0, 432109.0, 432649.0, 435912.0, 442694.0, 74659.0, 81272.0, 84693.0, 85576.0, 87295.1270487287, 922.2299380017627, 957.30595318395, 957.7318350861769, 973.756560859785, 974.936629119105, 15880.444184453489, 16105.0, 16220.51338459848, 16312.265948032129, 16345.836541634431, 35698.8215058923, 36653.26693466169, 37643.435348470346, 39650.0, 42425.0, 53739.46260537501, 54126.72936635291, 54731.45268547423, 54938.45061549333, 55191.0, 7261.201267860425, 7567.432442566939, 7594.924050759642, 7781.610919453891, 7840.0, 10058.0, 11746.0, 11858.288502689866, 11986.70033249141, 12161.45273462714, 9739.269554783277, 9784.0, 10006.14947729422, 10126.189904807745, 11087.223894327642, 10451.843222351823, 10502.632407865687, 10597.364158150536, 10802.621908233174, 10925.016748492673, 10339.276250662382, 10697.304675196086, 10731.0, 10960.0, 11178.664640060812, 7587.2412758723685, 7715.344195743424, 7725.690972361042, 7819.257170568787, 7911.367090632621, 9472.431654100776, 10296.330738501973, 10396.792064158522, 10530.210234232616, 10931.18016148776], "value": 8289.563790180086, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.96910077248, 1384.6815232496526, 1427.8929080318976, 1537.3773621683215, 1747.3884140550801, 6711.597304161747, 7556.508826926252, 7779.2998630123275, 7787.4938129021575, 7990.440669153161, 7695.192004839495, 7844.568548729818, 7848.332891704212, 7850.725224617139, 7862.606869656523, 2190.759016508179, 2218.090582861027, 2220.4448887778058, 2221.688963545105, 2222.377734234417, 2175.8476906616543, 2188.8248940084836, 2232.3191426614876, 2238.29493709481, 2238.6865838782583, 2195.8243340532717, 2197.263916587937, 2207.547452772186, 2209.028027667829, 2229.3423440085135, 2158.719366482355, 2164.935051948444, 2165.9133634654595, 2167.1873047607146, 2168.5229249565073, 2147.806697397242, 2182.0726191368603, 2184.3010236724253, 2185.694002839604, 2188.2122435923216, 7714.807129821743, 7829.569373684445, 7839.1768864269, 7890.8816367754525, 7961.084475285301, 4624.306354046887, 4716.339712440261, 4738.64460165489, 4746.477887432379, 4751.4535828379485, 2813.7186281371905, 2942.823430594171, 2973.8810447582073, 2977.568252603382, 2983.4033193361197, 970.8932017478072, 971.455984648592, 984.9409035457824, 987.8172538080478, 987.9259055570789, 10610.363378197255, 10713.946430267877, 10719.571217151304, 10725.839112413398, 10756.139508839275, 4109.815058322382, 4151.878992671962, 4174.540800511942, 4200.096979149497, 4228.408022876738, 8402.369822263354, 8506.447080939843, 8507.191816777397, 8515.57422128891, 8515.787105322293, 4667.229907065296, 4669.0428462165855, 4671.0, 4671.089137618132, 4672.8551504881525, 3714.4985426967555, 3816.561095474056, 4234.0, 4347.673924455678, 4352.7823608819435, 3517.613062563167, 3543.6279190685123, 3588.4617307404096, 3607.2424790793702, 3723.906902327409, 1048.6067724603274, 1059.915206783471, 1069.9946500267604, 1073.066432203972, 1074.4037059431935, 706.9646517674096, 855.255927343203, 893.5889490834289, 902.3863772634671, 942.6182396129578, 5422.566194704415, 5627.071533196977, 5686.09022556389, 5702.51528620072, 5774.682392468455, 879.3185281406961, 911.6307895302413, 941.2187884056217, 951.981379923489, 977.1498796047484, 3650.762700424464, 3742.2702572998546, 3748.194138260287, 3768.7563104176106, 3776.0937375029794, 9165.266778657824, 9486.715398538056, 9516.143547080657, 9530.0, 9770.36492627977, 9601.423914565148, 9636.0, 9636.42181469114, 9649.614015439443, 9684.612615495442, 7038.3665470107935, 7495.287947644965, 7499.400047996147, 7514.887276690854, 7521.435892307989, 5442.727863606883, 5578.63738856973, 5669.574781891292, 5680.431956804452, 5754.280714910637, 8730.745409865563, 8863.689770857989, 9068.047854975397, 9117.723518707253, 9170.358074934957, 8652.913470865462, 8989.370744047854, 9219.354645174773, 9267.119623635745, 9328.486933218737, 6844.0, 6974.37230649244, 6996.965015174892, 7020.0522929405915, 7030.050943122613, 7524.510906791041, 7703.422243331885, 7724.575148366894, 7741.071071471234, 7745.457817952689, 6874.106366172366, 6955.860882782417, 7015.298470152944, 7108.182559005781, 7185.137783466018, 6256.374362563707, 6393.4885209184085, 6437.969924812008, 6679.532432729857, 6688.765893193715, 6498.480121590356, 6531.477481801539, 6571.638559879252, 6574.704138313692, 6585.901211481927, 4502.0, 4697.600703940203, 4703.223968045371, 4784.832530861403, 4811.109944660249, 4758.405199350082, 4803.063402636452, 4837.782299796448, 4864.051509955525, 4887.951120488892], "value": 4190.68237166613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.78407978096214, 300.34524736075355, 301.5175718849839, 303.55074489755145, 308.2571003880647, 4773.546513081256, 4781.904361912764, 4785.784639691214, 4792.472827988919, 4832.4684284728655, 4715.292706094087, 4717.69334993226, 4746.596539294164, 4789.640776941726, 4792.90414191716, 3552.538170037898, 3675.1363429594026, 3702.61122582129, 3771.057235691077, 3802.9809850950846, 3728.2170744143764, 3764.4729737836724, 3773.584905660375, 3785.5646600640866, 3804.2962052020466, 3728.639046747931, 3728.850845966158, 3740.831662575191, 3749.7000239980734, 3752.5309336332957, 3547.5920269168987, 3630.382834918057, 3743.3636281832155, 3756.9624303756887, 3776.811159442017, 3326.8170250636203, 3343.3480471308103, 3350.564426624523, 3354.3626710924846, 3362.6301106878223, 4626.756023328683, 4638.327442520816, 4650.558196971282, 4661.370714953506, 4670.789814458358, 4178.35235538489, 4249.192653395845, 4259.0, 4266.080263881266, 4279.443672322609, 4851.56335929768, 4899.314096026559, 4915.311856340079, 4915.729634870081, 4927.993923706536, 3592.7485076044686, 3596.4065929121925, 3597.442396428558, 3609.5668519777796, 3635.6546128118052, 4906.656534042583, 5203.98522288154, 5223.347081614798, 5228.660137091077, 5232.215167724834, 3572.214112895159, 3587.551556055493, 3587.551556055493, 3596.6403359663823, 3606.9819650901577, 3646.908827279339, 3669.6697297243377, 3675.9264814703556, 3689.8893033209047, 3693.298273328059, 4158.958410415918, 4197.727147735387, 4214.957850421457, 4220.0, 4232.936505952413, 4785.832495862628, 4935.210366341368, 4953.578945789648, 4953.950460495421, 4956.727379994135, 1252.843394575678, 1254.5948537637903, 1254.6637830710265, 1262.9706788966962, 1264.3804535777588, 788.9408294377945, 803.5419810707957, 804.5856383962264, 807.3097501636134, 822.8189798244376, 4390.582894625006, 4454.131444368382, 4470.798814053375, 4482.686211965131, 4513.480949690762, 5096.108181068373, 5097.617678674114, 5123.308353372247, 5139.537441630271, 5140.434552199255, 4490.753008584494, 4492.326151077365, 4492.528284530077, 4495.483019452739, 4518.480374756879, 2570.7043689975894, 2742.3555464465835, 2744.698083210845, 2745.3136715821047, 2747.587861820743, 4880.0, 4894.437139728905, 4914.483979182134, 4925.310456536088, 4932.926006109911, 5030.346055012825, 5291.920621190685, 5301.602379821529, 5318.308619879391, 5328.946710532922, 4886.92669609949, 4905.3132561441425, 4917.754112294371, 4923.212286034217, 4926.310316555685, 5146.382434107931, 5153.845384638467, 5157.381114266312, 5168.638195326291, 5197.324347834758, 4859.566427903847, 5020.146575082172, 5033.697978121324, 5046.6719663221775, 5055.216441451581, 4893.584045356185, 4901.681390709735, 4913.533214344775, 4915.705057696549, 4922.4339200992345, 3576.0, 3599.226166374242, 3600.4599310103695, 3601.369760291886, 3610.693091087287, 4891.217405215149, 5155.252488389237, 5159.690418574897, 5168.689878607295, 5198.584113271005, 4930.063287975273, 4962.833734072562, 4969.552740253394, 4985.900281994413, 5033.899322013613, 4987.80292729735, 4988.625853061108, 4995.175795993766, 5059.886824898516, 5061.4432412435335, 4765.9523404765505, 4886.780094895809, 4890.486498917562, 4910.165271903857, 4931.654784165074, 4748.738819364969, 4854.344663470528, 4966.602671786321, 4967.230079337708, 4970.570306488656, 4915.926261106015, 4929.630277729113, 4933.260010998379, 4952.257161425671, 4956.306117143675], "value": 3765.4534436880895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.2909025530384, 646.6766616691657, 662.4811851382397, 732.6153769271135, 746.7423738810108, 12509.562165324216, 13637.522686705955, 13648.249346285951, 13665.931670341646, 13672.658183545416, 12339.753204935896, 12776.233425994447, 12945.805812912813, 12988.870111298884, 13021.088523803337, 781.2187812187813, 791.9920800791976, 792.9484583502083, 793.4683761879526, 796.4942261663828, 627.4509803921569, 755.7921571567808, 776.7436745873877, 782.0810547606568, 783.682608543538, 833.1293797981094, 835.340081335744, 835.7075023741672, 838.5094719589048, 839.9160083991583, 831.2850948184556, 833.7998880268752, 834.0825092398373, 834.170000849159, 834.407570624851, 473.69210013491164, 482.3945947835444, 483.5696230354987, 484.98302559410604, 590.7194082810673, 11863.228890122115, 12285.447154878051, 12321.322327271997, 12334.629961101182, 12357.25856448616, 11629.883701162964, 12188.390580470941, 12226.694332641666, 12389.946854517377, 12563.874361256454, 11652.417379131095, 11732.17874748768, 11797.823032654516, 11942.223755455867, 12413.255204687748, 3948.4077388391684, 4002.8398864045403, 4027.597240275978, 4045.702752256901, 4125.731827431207, 60448.395516044715, 61744.382556174314, 61759.99999999955, 61953.69023154901, 62423.375766242214, 9558.617655293849, 9698.660546880967, 10159.390436573682, 10329.638462653771, 10358.171346292278, 16524.25640846165, 17894.5526361842, 17975.1012449377, 17988.280468781362, 18019.18913648889, 6426.196725409324, 6626.9337306627285, 6642.767503137367, 6782.423494003065, 6810.284920083419, 3614.367485689994, 3646.5988741238443, 3667.101560117735, 3679.3745063339297, 3720.051386896324, 2767.0868613357543, 2840.801143919947, 2868.3689588290545, 2869.4404591104535, 2869.497837878363, 493.50156342094743, 499.3059647090547, 503.94456609772897, 507.9669821461593, 509.41417370024766, 475.40099474662173, 562.5893098038455, 568.0967262053355, 578.9710514474343, 588.1295682390087, 7232.602206878672, 7285.526440781332, 7636.579988100597, 7866.646000929972, 7891.802704932308, 526.9683818970874, 553.4078535966477, 555.9555235581224, 569.6667449541982, 602.1690067706625, 3523.0, 3578.248567800791, 3586.0317714217017, 3621.6016238213774, 3638.508801311789, 51347.22979155316, 91662.0, 92010.53994729984, 93384.0, 93849.5307523458, 82002.58998704968, 83919.58040209759, 84629.0, 85630.0, 85849.0848934964, 48222.0, 70297.29702703112, 71669.0, 73274.26725732781, 76251.0, 6619.50353723463, 6876.209235937731, 6898.655067246718, 6938.826529336605, 7039.366457018893, 31532.684673152973, 37777.0, 44135.33796993112, 44782.3282650767, 45574.08851822879, 56437.43562564486, 56794.43205567892, 62099.6895015522, 67051.66474167598, 68130.0, 8906.777330566787, 8960.865587016331, 9088.045755195873, 9324.0, 9346.672866449913, 10269.281150319704, 10548.367097974146, 10763.246572739834, 11990.40047997614, 12378.75242495127, 12133.332666703416, 12176.695582610506, 12860.356982150668, 12868.806967895676, 12985.28580928058, 7427.96286018588, 11740.002099821611, 12224.205426647595, 12372.257664540157, 13091.149075310075, 10778.838317425401, 10822.296550724177, 10864.891351086704, 11097.278676885977, 11468.655940321803, 10500.527476263434, 10606.734831628963, 10702.785944281226, 10918.12654987615, 10937.726556836144, 9901.950490247791, 10122.342047766871, 10135.442550659489, 10145.239107067147, 10509.211809114195], "value": 6359.628063697915, "unit": "score"}], "name": "dromaeo_css", "value": 6412.761287445642, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0, 117.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 124.0, 174.0, 124.0, 123.0, 144.0, 122.0, 160.0, 122.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 162.0, 161.0, 162.0, 162.0, 161.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 107.0, 76.0, 77.0, 75.0, 75.0, 98.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 110.0, 84.0, 85.0, 85.0, 125.0, 85.0, 85.0, 134.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 61.0, 62.0, 63.0, 71.0, 63.0, 63.0, 62.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 78.0, 79.0, 79.0, 80.0, 79.0, 84.0, 77.0, 80.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 141.0, 146.0, 137.0, 131.0, 135.0, 146.0, 224.0, 140.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 165.0, 165.0, 166.0, 169.0, 170.0, 167.0, 168.0, 164.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 68.0, 67.0, 73.0, 66.0, 65.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1537.5, "alertThreshold": 2.0}]} 08:52:38 INFO - Return code: 0 08:52:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:52:38 INFO - # TBPL SUCCESS # 08:52:38 INFO - Running post-action listener: _resource_record_post_action 08:52:38 INFO - ##### 08:52:38 INFO - ##### Finished run-tests step (success) 08:52:38 INFO - ##### 08:52:38 INFO - Running post-run listener: _resource_record_post_run 08:52:38 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 540258304; Read time: 12; Write time: 652140 08:52:38 INFO - TinderboxPrint: CPU usage
13.0% 08:52:38 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 12 08:52:38 INFO - TinderboxPrint: I/O write bytes / time
540,258,304 / 652,140 08:52:38 INFO - TinderboxPrint: CPU idle
8,334.8 (87.0%) 08:52:38 INFO - TinderboxPrint: CPU user
1,199.4 (12.5%) 08:52:38 INFO - TinderboxPrint: Swap in / out
0 / 0 08:52:38 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 246890496; Read time: 0; Write time: 400828 08:52:38 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 12288; Write bytes: 289411072; Read time: 12; Write time: 250812 08:52:38 INFO - Running post-run listener: _upload_blobber_files 08:52:38 INFO - Blob upload gear active. 08:52:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:52:38 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 08:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:52:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:52:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:52:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:48 INFO - (blobuploader) - INFO - Done attempting. 08:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:48 INFO - (blobuploader) - INFO - Done attempting. 08:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:52:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:49 INFO - (blobuploader) - INFO - Done attempting. 08:52:49 INFO - (blobuploader) - INFO - Iteration through files over. 08:52:49 INFO - Return code: 0 08:52:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:52:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:52:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18f33910c690a17fe2b127590eeaf6b90907410e6ae7820625c409596271ea79955611b19c0c1fb3f8bf68f8192a89521184d21a289db7014e3fb74a8c11fb1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/97aa1f23006b12eba3d8cd23b24e87d4df2040ad31c0c6e6dceb23120ec13517cccffbf08d4ab1e263ccd299ac6222b7a531090a90056bf5cb281b254ec7f1e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1367942517a7087cb66ba84102768777d529eae8ef89e2ae1eef58c56f5edf7555367eef08404cfbab198cb4e3688a3090b0dcfec50a07bf5192c2ad3c7e49eb"} 08:52:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:52:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:52:49 INFO - Contents: 08:52:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18f33910c690a17fe2b127590eeaf6b90907410e6ae7820625c409596271ea79955611b19c0c1fb3f8bf68f8192a89521184d21a289db7014e3fb74a8c11fb1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/97aa1f23006b12eba3d8cd23b24e87d4df2040ad31c0c6e6dceb23120ec13517cccffbf08d4ab1e263ccd299ac6222b7a531090a90056bf5cb281b254ec7f1e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1367942517a7087cb66ba84102768777d529eae8ef89e2ae1eef58c56f5edf7555367eef08404cfbab198cb4e3688a3090b0dcfec50a07bf5192c2ad3c7e49eb"} 08:52:49 INFO - Running post-run listener: copy_logs_to_upload_dir 08:52:49 INFO - Copying logs to upload dir... 08:52:49 INFO - mkdir: /builds/slave/test/build/upload/logs 08:52:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.712699 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-19 08:52:49.054836) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 08:52:49.061781) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18f33910c690a17fe2b127590eeaf6b90907410e6ae7820625c409596271ea79955611b19c0c1fb3f8bf68f8192a89521184d21a289db7014e3fb74a8c11fb1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/97aa1f23006b12eba3d8cd23b24e87d4df2040ad31c0c6e6dceb23120ec13517cccffbf08d4ab1e263ccd299ac6222b7a531090a90056bf5cb281b254ec7f1e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1367942517a7087cb66ba84102768777d529eae8ef89e2ae1eef58c56f5edf7555367eef08404cfbab198cb4e3688a3090b0dcfec50a07bf5192c2ad3c7e49eb"} build_url:https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/M12mXXNDT6mQtYyXZ7SRAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/18f33910c690a17fe2b127590eeaf6b90907410e6ae7820625c409596271ea79955611b19c0c1fb3f8bf68f8192a89521184d21a289db7014e3fb74a8c11fb1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/97aa1f23006b12eba3d8cd23b24e87d4df2040ad31c0c6e6dceb23120ec13517cccffbf08d4ab1e263ccd299ac6222b7a531090a90056bf5cb281b254ec7f1e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1367942517a7087cb66ba84102768777d529eae8ef89e2ae1eef58c56f5edf7555367eef08404cfbab198cb4e3688a3090b0dcfec50a07bf5192c2ad3c7e49eb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 08:52:49.087690) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:52:49.088171) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468942325.67787-2003419085 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 08:52:49.173673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 08:52:49.174011) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 08:52:49.174317) ========= ========= Total master_lag: 0.16 =========