builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1468917635.11 results: success (0) buildid: 20160718223049 builduid: c99724ae7c0d4bf6901ec00b6744d831 revision: df35aa6dffb62d73cbc404094e2cf5ee147bf587 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.110670) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.111129) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.111419) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.125914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.126310) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 01:40:35-- 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% 217M=0s 2016-07-19 01:40:35 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073984 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.214205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.214536) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017741 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.263923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.264273) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev df35aa6dffb62d73cbc404094e2cf5ee147bf587 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev df35aa6dffb62d73cbc404094e2cf5ee147bf587 --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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 01:40:35,626 truncating revision to first 12 chars 2016-07-19 01:40:35,626 Setting DEBUG logging. 2016-07-19 01:40:35,626 attempt 1/10 2016-07-19 01:40:35,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/df35aa6dffb6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 01:40:35,949 unpacking tar archive at: mozilla-inbound-df35aa6dffb6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664167 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.942668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.942996) ========= script_repo_revision: df35aa6dffb62d73cbc404094e2cf5ee147bf587 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.943395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.943664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 01:40:35.953318) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-19 01:40:35.953640) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:40:36 INFO - MultiFileLogger online at 20160719 01:40:36 in /builds/slave/test 01:40:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 01:40:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:40:36 INFO - {'append_to_log': False, 01:40:36 INFO - 'base_work_dir': '/builds/slave/test', 01:40:36 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 01:40:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:40:36 INFO - 'branch': 'Mozilla-Inbound', 01:40:36 INFO - 'buildbot_json_path': 'buildprops.json', 01:40:36 INFO - 'config_files': ('talos/linux_config.py',), 01:40:36 INFO - 'default_actions': ('clobber', 01:40:36 INFO - 'read-buildbot-config', 01:40:36 INFO - 'download-and-extract', 01:40:36 INFO - 'populate-webroot', 01:40:36 INFO - 'create-virtualenv', 01:40:36 INFO - 'install', 01:40:36 INFO - 'run-tests'), 01:40:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:40:36 INFO - 'download_minidump_stackwalk': True, 01:40:36 INFO - 'download_symbols': 'ondemand', 01:40:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:40:36 INFO - 'tooltool.py': '/tools/tooltool.py', 01:40:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:40:36 INFO - '/tools/misc-python/virtualenv.py')}, 01:40:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:40:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:40:36 INFO - 'installer_path': 'installer.exe', 01:40:36 INFO - 'log_level': 'info', 01:40:36 INFO - 'log_name': 'talos', 01:40:36 INFO - 'log_to_console': True, 01:40:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:40:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:40:36 INFO - 'opt_config_files': (), 01:40:36 INFO - 'pip_index': False, 01:40:36 INFO - 'sps_profile': False, 01:40:36 INFO - 'sps_profile_interval': 0, 01:40:36 INFO - 'suite': 'dromaeojs', 01:40:36 INFO - 'system_bits': '32', 01:40:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:40:36 INFO - 'title': 'talos-linux64-ix-067', 01:40:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:40:36 INFO - 'use_talos_json': True, 01:40:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:40:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:40:36 INFO - 'work_dir': 'build'} 01:40:36 INFO - ##### 01:40:36 INFO - ##### Running clobber step. 01:40:36 INFO - ##### 01:40:36 INFO - Running pre-action listener: _resource_record_pre_action 01:40:36 INFO - Running main action method: clobber 01:40:36 INFO - rmtree: /builds/slave/test/build 01:40:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:40:36 INFO - Running post-action listener: _resource_record_post_action 01:40:36 INFO - ##### 01:40:36 INFO - ##### Finished clobber step (success) 01:40:36 INFO - ##### 01:40:36 INFO - ##### 01:40:36 INFO - ##### Running read-buildbot-config step. 01:40:36 INFO - ##### 01:40:36 INFO - Running pre-action listener: _resource_record_pre_action 01:40:36 INFO - Running main action method: read_buildbot_config 01:40:36 INFO - Using buildbot properties: 01:40:36 INFO - { 01:40:36 INFO - "project": "", 01:40:36 INFO - "product": "firefox", 01:40:36 INFO - "script_repo_revision": "production", 01:40:36 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 01:40:36 INFO - "repository": "", 01:40:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 01:40:36 INFO - "buildid": "20160718223049", 01:40:36 INFO - "pgo_build": "True", 01:40:36 INFO - "basedir": "/builds/slave/test", 01:40:36 INFO - "buildnumber": 2085, 01:40:36 INFO - "slavename": "talos-linux64-ix-067", 01:40:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:40:36 INFO - "platform": "ubuntu64_hw", 01:40:36 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 01:40:36 INFO - "branch": "mozilla-inbound", 01:40:36 INFO - "repo_path": "integration/mozilla-inbound", 01:40:36 INFO - "revision": "df35aa6dffb62d73cbc404094e2cf5ee147bf587", 01:40:36 INFO - "stage_platform": "linux64-pgo", 01:40:36 INFO - "builduid": "c99724ae7c0d4bf6901ec00b6744d831", 01:40:36 INFO - "slavebuilddir": "test" 01:40:36 INFO - } 01:40:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 01:40:36 INFO - Running post-action listener: _resource_record_post_action 01:40:36 INFO - ##### 01:40:36 INFO - ##### Finished read-buildbot-config step (success) 01:40:36 INFO - ##### 01:40:36 INFO - ##### 01:40:36 INFO - ##### Running download-and-extract step. 01:40:36 INFO - ##### 01:40:36 INFO - Running pre-action listener: _resource_record_pre_action 01:40:36 INFO - Running main action method: download_and_extract 01:40:36 INFO - mkdir: /builds/slave/test/build/tests 01:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:36 INFO - https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:40:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:40:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:40:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:37 INFO - Downloaded 1361 bytes. 01:40:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:40:37 INFO - Using the following test package requirements: 01:40:37 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 01:40:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:40:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:40:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'jsshell-linux-x86_64.zip'], 01:40:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:40:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 01:40:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:40:37 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:40:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:40:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:40:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:40:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:40: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')]} 01:40:37 INFO - https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:40:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:40:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:40:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:38 INFO - Downloaded 24336355 bytes. 01:40:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:40:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:40:40 INFO - Return code: 0 01:40:40 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 01:40:40 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')]} 01:40:40 INFO - https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:40:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:40:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:40:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:41 INFO - Downloaded 24336355 bytes. 01:40:41 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'] 01:40:41 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 01:40:42 INFO - Return code: 0 01:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:42 INFO - https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:40:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 01:40:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 01:40:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:43 INFO - Downloaded 14403276 bytes. 01:40:43 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'] 01:40:43 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 01:40:43 INFO - Return code: 0 01:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:43 INFO - https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:40:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:40:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:40:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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 01:40:45 INFO - Downloaded 60839183 bytes. 01:40:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:40:45 INFO - mkdir: /builds/slave/test/properties 01:40:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:45 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:45 INFO - Contents: 01:40:45 INFO - build_url:https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:40:46 INFO - Running post-action listener: _resource_record_post_action 01:40:46 INFO - Running post-action listener: set_extra_try_arguments 01:40:46 INFO - ##### 01:40:46 INFO - ##### Finished download-and-extract step (success) 01:40:46 INFO - ##### 01:40:46 INFO - ##### 01:40:46 INFO - ##### Running populate-webroot step. 01:40:46 INFO - ##### 01:40:46 INFO - Running pre-action listener: _resource_record_pre_action 01:40:46 INFO - Running main action method: populate_webroot 01:40:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:46 INFO - '--tppagecycles', 01:40:46 INFO - '7'], 01:40:46 INFO - 'tests': ['tsvgm']}}, 01:40:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['damp', 'tps']}, 01:40:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'tests': ['damp', 'tps']}, 01:40:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['dromaeo_dom']}, 01:40:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['basic_compositor_video']}, 01:40:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:40:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['a11yr', 01:40:46 INFO - 'ts_paint', 01:40:46 INFO - 'tpaint', 01:40:46 INFO - 'sessionrestore', 01:40:46 INFO - 'sessionrestore_no_auto_restore', 01:40:46 INFO - 'tabpaint']}, 01:40:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['tsvgx', 01:40:46 INFO - 'tsvgr_opacity', 01:40:46 INFO - 'tart', 01:40:46 INFO - 'tscrollx', 01:40:46 INFO - 'cart']}, 01:40:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:46 INFO - 'tsvgr_opacity', 01:40:46 INFO - 'tart', 01:40:46 INFO - 'tscrollx', 01:40:46 INFO - 'cart']}, 01:40:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'talos_options': ['--disable-e10s'], 01:40:46 INFO - 'tests': ['tp5o']}, 01:40:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'tests': ['tp5o']}, 01:40:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'talos_options': ['--disable-e10s', 01:40:46 INFO - '--xperf_path', 01:40:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:46 INFO - 'tests': ['tp5n']}, 01:40:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:46 INFO - 'talos_options': ['--xperf_path', 01:40:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:46 INFO - 'tests': ['tp5n']}}, 01:40:46 INFO - 'talos.zip': {'path': '', 01:40:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:46 INFO - Running post-action listener: _resource_record_post_action 01:40:46 INFO - ##### 01:40:46 INFO - ##### Finished populate-webroot step (success) 01:40:46 INFO - ##### 01:40:46 INFO - ##### 01:40:46 INFO - ##### Running create-virtualenv step. 01:40:46 INFO - ##### 01:40:46 INFO - Running pre-action listener: _resource_record_pre_action 01:40:46 INFO - Running main action method: create_virtualenv 01:40:46 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:46 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 01:40:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:46 INFO - Using real prefix '/usr' 01:40:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:46 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:48 INFO - Installing pip.................done. 01:40:48 INFO - Return code: 0 01:40:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:40:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:40:48 INFO - Using env: {'DISPLAY': ':0', 01:40:48 INFO - 'HOME': '/home/cltbld', 01:40:48 INFO - 'LANG': 'en_US.UTF-8', 01:40:48 INFO - 'LANGUAGE': 'en_US:en', 01:40:48 INFO - 'LOGNAME': 'cltbld', 01:40:48 INFO - 'MAIL': '/var/mail/cltbld', 01:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:48 INFO - 'MOZ_NO_REMOTE': '1', 01:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:48 INFO - 'NO_EM_RESTART': '1', 01:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:48 INFO - 'PWD': '/builds/slave/test', 01:40:48 INFO - 'SHELL': '/bin/bash', 01:40:48 INFO - 'SHLVL': '1', 01:40:48 INFO - 'TERM': 'linux', 01:40:48 INFO - 'TMOUT': '86400', 01:40:48 INFO - 'USER': 'cltbld', 01:40:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:48 INFO - '_': '/tools/buildbot/bin/python'} 01:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:40:48 INFO - Cleaning up... 01:40:48 INFO - Return code: 0 01:40:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:40:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:40:48 INFO - Using env: {'DISPLAY': ':0', 01:40:48 INFO - 'HOME': '/home/cltbld', 01:40:48 INFO - 'LANG': 'en_US.UTF-8', 01:40:48 INFO - 'LANGUAGE': 'en_US:en', 01:40:48 INFO - 'LOGNAME': 'cltbld', 01:40:48 INFO - 'MAIL': '/var/mail/cltbld', 01:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:48 INFO - 'MOZ_NO_REMOTE': '1', 01:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:48 INFO - 'NO_EM_RESTART': '1', 01:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:48 INFO - 'PWD': '/builds/slave/test', 01:40:48 INFO - 'SHELL': '/bin/bash', 01:40:48 INFO - 'SHLVL': '1', 01:40:48 INFO - 'TERM': 'linux', 01:40:48 INFO - 'TMOUT': '86400', 01:40:48 INFO - 'USER': 'cltbld', 01:40:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:48 INFO - '_': '/tools/buildbot/bin/python'} 01:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:49 INFO - Downloading/unpacking psutil>=3.1.1 01:40:49 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 01:40:49 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 01:40:49 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 01:40:49 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 01:40:49 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 01:40:49 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 01:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:51 INFO - Installing collected packages: psutil 01:40:51 INFO - Running setup.py install for psutil 01:40:51 INFO - building 'psutil._psutil_linux' extension 01:40:51 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 01:40:51 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 01:40:51 INFO - building 'psutil._psutil_posix' extension 01:40:51 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 01:40:51 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 01:40:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:51 INFO - Successfully installed psutil 01:40:51 INFO - Cleaning up... 01:40:51 INFO - Return code: 0 01:40:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:40:51 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')]} 01:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:51 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')]} 01:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:51 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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:40:51 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 01:40:51 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 01:40:51 INFO - Using env: {'DISPLAY': ':0', 01:40:51 INFO - 'HOME': '/home/cltbld', 01:40:51 INFO - 'LANG': 'en_US.UTF-8', 01:40:51 INFO - 'LANGUAGE': 'en_US:en', 01:40:51 INFO - 'LOGNAME': 'cltbld', 01:40:51 INFO - 'MAIL': '/var/mail/cltbld', 01:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:51 INFO - 'MOZ_NO_REMOTE': '1', 01:40:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:51 INFO - 'NO_EM_RESTART': '1', 01:40:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:51 INFO - 'PWD': '/builds/slave/test', 01:40:51 INFO - 'SHELL': '/bin/bash', 01:40:51 INFO - 'SHLVL': '1', 01:40:51 INFO - 'TERM': 'linux', 01:40:51 INFO - 'TMOUT': '86400', 01:40:51 INFO - 'USER': 'cltbld', 01:40:51 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:51 INFO - '_': '/tools/buildbot/bin/python'} 01:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:40:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:40:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:40:53 INFO - Installing collected packages: mozsystemmonitor 01:40:53 INFO - Running setup.py install for mozsystemmonitor 01:40:54 INFO - Successfully installed mozsystemmonitor 01:40:54 INFO - Cleaning up... 01:40:54 INFO - Return code: 0 01:40:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:54 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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:40:54 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 01:40:54 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 01:40:54 INFO - Using env: {'DISPLAY': ':0', 01:40:54 INFO - 'HOME': '/home/cltbld', 01:40:54 INFO - 'LANG': 'en_US.UTF-8', 01:40:54 INFO - 'LANGUAGE': 'en_US:en', 01:40:54 INFO - 'LOGNAME': 'cltbld', 01:40:54 INFO - 'MAIL': '/var/mail/cltbld', 01:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:54 INFO - 'MOZ_NO_REMOTE': '1', 01:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:54 INFO - 'NO_EM_RESTART': '1', 01:40:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:54 INFO - 'PWD': '/builds/slave/test', 01:40:54 INFO - 'SHELL': '/bin/bash', 01:40:54 INFO - 'SHLVL': '1', 01:40:54 INFO - 'TERM': 'linux', 01:40:54 INFO - 'TMOUT': '86400', 01:40:54 INFO - 'USER': 'cltbld', 01:40:54 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:54 INFO - '_': '/tools/buildbot/bin/python'} 01:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:54 INFO - Downloading/unpacking jsonschema==2.5.1 01:40:54 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 01:40:54 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 01:40:54 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 01:40:54 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 01:40:54 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 01:40:54 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 01:40:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:40:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:56 INFO - Downloading functools32-3.2.3-2.tar.gz 01:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:40:56 INFO - warning: no files found matching '*.txt' 01:40:56 INFO - no previously-included directories found matching 'build' 01:40:56 INFO - no previously-included directories found matching 'dist' 01:40:56 INFO - no previously-included directories found matching '.git*' 01:40:56 INFO - Installing collected packages: jsonschema, functools32 01:40:56 INFO - Running setup.py install for functools32 01:40:56 INFO - warning: no files found matching '*.txt' 01:40:56 INFO - no previously-included directories found matching 'build' 01:40:56 INFO - no previously-included directories found matching 'dist' 01:40:56 INFO - no previously-included directories found matching '.git*' 01:40:56 INFO - Successfully installed jsonschema functools32 01:40:56 INFO - Cleaning up... 01:40:56 INFO - Return code: 0 01:40:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:40:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:40:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:40:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:40:56 INFO - Using env: {'DISPLAY': ':0', 01:40:56 INFO - 'HOME': '/home/cltbld', 01:40:56 INFO - 'LANG': 'en_US.UTF-8', 01:40:56 INFO - 'LANGUAGE': 'en_US:en', 01:40:56 INFO - 'LOGNAME': 'cltbld', 01:40:56 INFO - 'MAIL': '/var/mail/cltbld', 01:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:56 INFO - 'MOZ_NO_REMOTE': '1', 01:40:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:56 INFO - 'NO_EM_RESTART': '1', 01:40:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:56 INFO - 'PWD': '/builds/slave/test', 01:40:56 INFO - 'SHELL': '/bin/bash', 01:40:56 INFO - 'SHLVL': '1', 01:40:56 INFO - 'TERM': 'linux', 01:40:56 INFO - 'TMOUT': '86400', 01:40:56 INFO - 'USER': 'cltbld', 01:40:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:56 INFO - '_': '/tools/buildbot/bin/python'} 01:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:56 INFO - Downloading/unpacking blobuploader==1.2.4 01:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:58 INFO - Downloading blobuploader-1.2.4.tar.gz 01:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:40:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:40:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:59 INFO - Downloading docopt-0.6.1.tar.gz 01:40:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:40:59 INFO - Installing collected packages: blobuploader, requests, docopt 01:40:59 INFO - Running setup.py install for blobuploader 01:40:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:40:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:40:59 INFO - Running setup.py install for requests 01:41:00 INFO - Running setup.py install for docopt 01:41:00 INFO - Successfully installed blobuploader requests docopt 01:41:00 INFO - Cleaning up... 01:41:00 INFO - Return code: 0 01:41:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:00 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')]} 01:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:00 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')]} 01:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:00 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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:41:00 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 01:41:00 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 01:41:00 INFO - Using env: {'DISPLAY': ':0', 01:41:00 INFO - 'HOME': '/home/cltbld', 01:41:00 INFO - 'LANG': 'en_US.UTF-8', 01:41:00 INFO - 'LANGUAGE': 'en_US:en', 01:41:00 INFO - 'LOGNAME': 'cltbld', 01:41:00 INFO - 'MAIL': '/var/mail/cltbld', 01:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:00 INFO - 'MOZ_NO_REMOTE': '1', 01:41:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:00 INFO - 'NO_EM_RESTART': '1', 01:41:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:00 INFO - 'PWD': '/builds/slave/test', 01:41:00 INFO - 'SHELL': '/bin/bash', 01:41:00 INFO - 'SHLVL': '1', 01:41:00 INFO - 'TERM': 'linux', 01:41:00 INFO - 'TMOUT': '86400', 01:41:00 INFO - 'USER': 'cltbld', 01:41:00 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:00 INFO - '_': '/tools/buildbot/bin/python'} 01:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:00 INFO - Running setup.py (path:/tmp/pip-LUOh49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:00 INFO - Running setup.py (path:/tmp/pip-VfWuOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:00 INFO - Running setup.py (path:/tmp/pip-B5K080-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:00 INFO - Running setup.py (path:/tmp/pip-sFF0n5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:00 INFO - Running setup.py (path:/tmp/pip-JysB94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:00 INFO - Running setup.py (path:/tmp/pip-hVdnk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:00 INFO - Running setup.py (path:/tmp/pip-DeoMjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:00 INFO - Running setup.py (path:/tmp/pip-W_o8dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:00 INFO - Running setup.py (path:/tmp/pip-KYJHaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:01 INFO - Running setup.py (path:/tmp/pip-ZE4VDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:01 INFO - Running setup.py (path:/tmp/pip-g9IbjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:01 INFO - Running setup.py (path:/tmp/pip-WXRatv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:01 INFO - Running setup.py (path:/tmp/pip-5rAu39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:01 INFO - Running setup.py (path:/tmp/pip-xE0dE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:01 INFO - Running setup.py (path:/tmp/pip-BHQ358-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:01 INFO - Running setup.py (path:/tmp/pip-QbIY7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:01 INFO - Running setup.py (path:/tmp/pip-qGl29B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:01 INFO - Running setup.py install for manifestparser 01:41:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:01 INFO - Running setup.py install for mozcrash 01:41:01 INFO - Running setup.py install for mozdebug 01:41:01 INFO - Running setup.py install for mozdevice 01:41:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozfile 01:41:02 INFO - Running setup.py install for mozhttpd 01:41:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozinfo 01:41:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozInstall 01:41:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozleak 01:41:02 INFO - Running setup.py install for mozlog 01:41:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for moznetwork 01:41:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozprocess 01:41:02 INFO - Running setup.py install for mozprofile 01:41:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:02 INFO - Running setup.py install for mozrunner 01:41:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:03 INFO - Running setup.py install for mozscreenshot 01:41:03 INFO - Running setup.py install for moztest 01:41:03 INFO - Running setup.py install for mozversion 01:41:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:41:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:41:03 INFO - Cleaning up... 01:41:03 INFO - Return code: 0 01:41:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:03 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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:41:03 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 01:41:03 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 01:41:03 INFO - Using env: {'DISPLAY': ':0', 01:41:03 INFO - 'HOME': '/home/cltbld', 01:41:03 INFO - 'LANG': 'en_US.UTF-8', 01:41:03 INFO - 'LANGUAGE': 'en_US:en', 01:41:03 INFO - 'LOGNAME': 'cltbld', 01:41:03 INFO - 'MAIL': '/var/mail/cltbld', 01:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:03 INFO - 'MOZ_NO_REMOTE': '1', 01:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:03 INFO - 'NO_EM_RESTART': '1', 01:41:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:03 INFO - 'PWD': '/builds/slave/test', 01:41:03 INFO - 'SHELL': '/bin/bash', 01:41:03 INFO - 'SHLVL': '1', 01:41:03 INFO - 'TERM': 'linux', 01:41:03 INFO - 'TMOUT': '86400', 01:41:03 INFO - 'USER': 'cltbld', 01:41:03 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:03 INFO - '_': '/tools/buildbot/bin/python'} 01:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:03 INFO - Running setup.py (path:/tmp/pip-oT1ldC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:03 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)) 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:03 INFO - Running setup.py (path:/tmp/pip-KKpS__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:03 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)) 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:03 INFO - Running setup.py (path:/tmp/pip-tWWjSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:03 INFO - Running setup.py (path:/tmp/pip-5GNtqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:03 INFO - Running setup.py (path:/tmp/pip-TNO749-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:03 INFO - Running setup.py (path:/tmp/pip-NnZAW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:04 INFO - Running setup.py (path:/tmp/pip-UGL083-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:04 INFO - Running setup.py (path:/tmp/pip-KMca3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:04 INFO - Running setup.py (path:/tmp/pip-zjqYFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:04 INFO - Running setup.py (path:/tmp/pip-JqhLtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:04 INFO - Running setup.py (path:/tmp/pip-77KE1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:04 INFO - Running setup.py (path:/tmp/pip-sLq33q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:04 INFO - Running setup.py (path:/tmp/pip-bMiKtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:04 INFO - Running setup.py (path:/tmp/pip-KQd_7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:04 INFO - Running setup.py (path:/tmp/pip-mYbnQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:04 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)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:04 INFO - Running setup.py (path:/tmp/pip-IdIh5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:04 INFO - Running setup.py (path:/tmp/pip-FoH8Vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:06 INFO - Downloading blessings-1.6.tar.gz 01:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:41:06 INFO - Installing collected packages: blessings 01:41:06 INFO - Running setup.py install for blessings 01:41:06 INFO - Successfully installed blessings 01:41:06 INFO - Cleaning up... 01:41:07 INFO - Return code: 0 01:41:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:07 INFO - Reading from file tmpfile_stdout 01:41:07 INFO - Current package versions: 01:41:07 INFO - argparse == 1.2.1 01:41:07 INFO - blessings == 1.6 01:41:07 INFO - blobuploader == 1.2.4 01:41:07 INFO - docopt == 0.6.1 01:41:07 INFO - functools32 == 3.2.3-2 01:41:07 INFO - jsonschema == 2.5.1 01:41:07 INFO - manifestparser == 1.1 01:41:07 INFO - mozInstall == 1.12 01:41:07 INFO - mozcrash == 0.17 01:41:07 INFO - mozdebug == 0.1 01:41:07 INFO - mozdevice == 0.48 01:41:07 INFO - mozfile == 1.2 01:41:07 INFO - mozhttpd == 0.7 01:41:07 INFO - mozinfo == 0.9 01:41:07 INFO - mozleak == 0.1 01:41:07 INFO - mozlog == 3.2 01:41:07 INFO - moznetwork == 0.27 01:41:07 INFO - mozprocess == 0.23 01:41:07 INFO - mozprofile == 0.28 01:41:07 INFO - mozrunner == 6.12 01:41:07 INFO - mozscreenshot == 0.1 01:41:07 INFO - mozsystemmonitor == 0.3 01:41:07 INFO - moztest == 0.7 01:41:07 INFO - mozversion == 1.4 01:41:07 INFO - psutil == 3.1.1 01:41:07 INFO - requests == 1.2.3 01:41:07 INFO - wsgiref == 0.1.2 01:41:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:07 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 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:41:07 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 01:41:07 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 01:41:07 INFO - Using env: {'DISPLAY': ':0', 01:41:07 INFO - 'HOME': '/home/cltbld', 01:41:07 INFO - 'LANG': 'en_US.UTF-8', 01:41:07 INFO - 'LANGUAGE': 'en_US:en', 01:41:07 INFO - 'LOGNAME': 'cltbld', 01:41:07 INFO - 'MAIL': '/var/mail/cltbld', 01:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:07 INFO - 'MOZ_NO_REMOTE': '1', 01:41:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:07 INFO - 'NO_EM_RESTART': '1', 01:41:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:07 INFO - 'PWD': '/builds/slave/test', 01:41:07 INFO - 'SHELL': '/bin/bash', 01:41:07 INFO - 'SHLVL': '1', 01:41:07 INFO - 'TERM': 'linux', 01:41:07 INFO - 'TMOUT': '86400', 01:41:07 INFO - 'USER': 'cltbld', 01:41:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:07 INFO - '_': '/tools/buildbot/bin/python'} 01:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 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)) 01:41:07 INFO - Cleaning up... 01:41:07 INFO - Return code: 0 01:41:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1731030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19f2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', '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 01:41:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:41:07 INFO - Using env: {'DISPLAY': ':0', 01:41:07 INFO - 'HOME': '/home/cltbld', 01:41:07 INFO - 'LANG': 'en_US.UTF-8', 01:41:07 INFO - 'LANGUAGE': 'en_US:en', 01:41:07 INFO - 'LOGNAME': 'cltbld', 01:41:07 INFO - 'MAIL': '/var/mail/cltbld', 01:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:07 INFO - 'MOZ_NO_REMOTE': '1', 01:41:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:07 INFO - 'NO_EM_RESTART': '1', 01:41:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:07 INFO - 'PWD': '/builds/slave/test', 01:41:07 INFO - 'SHELL': '/bin/bash', 01:41:07 INFO - 'SHLVL': '1', 01:41:07 INFO - 'TERM': 'linux', 01:41:07 INFO - 'TMOUT': '86400', 01:41:07 INFO - 'USER': 'cltbld', 01:41:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:07 INFO - '_': '/tools/buildbot/bin/python'} 01:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:41:07 INFO - Cleaning up... 01:41:07 INFO - Return code: 0 01:41:07 INFO - Running post-action listener: _resource_record_post_action 01:41:07 INFO - Running post-action listener: _start_resource_monitoring 01:41:07 INFO - Starting resource monitoring. 01:41:07 INFO - ##### 01:41:07 INFO - ##### Finished create-virtualenv step (success) 01:41:07 INFO - ##### 01:41:07 INFO - ##### 01:41:07 INFO - ##### Running install step. 01:41:07 INFO - ##### 01:41:07 INFO - Running pre-action listener: _resource_record_pre_action 01:41:07 INFO - Running main action method: install 01:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:07 INFO - Reading from file tmpfile_stdout 01:41:07 INFO - Detecting whether we're running mozinstall >=1.0... 01:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:41:07 INFO - Reading from file tmpfile_stdout 01:41:07 INFO - Output received: 01:41:07 INFO - Usage: mozinstall [options] installer 01:41:07 INFO - Options: 01:41:07 INFO - -h, --help show this help message and exit 01:41:07 INFO - -d DEST, --destination=DEST 01:41:07 INFO - Directory to install application into. [default: 01:41:07 INFO - "/builds/slave/test"] 01:41:07 INFO - --app=APP Application being installed. [default: firefox] 01:41:07 INFO - mkdir: /builds/slave/test/build/application 01:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:41:25 INFO - Reading from file tmpfile_stdout 01:41:25 INFO - Output received: 01:41:25 INFO - /builds/slave/test/build/application/firefox/firefox 01:41:25 INFO - Running post-action listener: _resource_record_post_action 01:41:25 INFO - ##### 01:41:25 INFO - ##### Finished install step (success) 01:41:25 INFO - ##### 01:41:25 INFO - ##### 01:41:25 INFO - ##### Running run-tests step. 01:41:25 INFO - ##### 01:41:25 INFO - Running pre-action listener: _resource_record_pre_action 01:41:25 INFO - Running main action method: run_tests 01:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:41:25 INFO - Python 2.7.3 01:41:25 INFO - Return code: 0 01:41:25 INFO - grabbing minidump binary from tooltool 01:41:25 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')]} 01:41:25 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 0x19f03b0>, '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 0x19f12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19f1740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:41:25 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 01:41:25 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 01:41:25 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 01:41:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:41:25 INFO - Return code: 0 01:41:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:41:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:41:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:41:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:41:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 01:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 01:41:25 INFO - Using env: {'DISPLAY': ':0', 01:41:25 INFO - 'HOME': '/home/cltbld', 01:41:25 INFO - 'LANG': 'en_US.UTF-8', 01:41:25 INFO - 'LANGUAGE': 'en_US:en', 01:41:25 INFO - 'LOGNAME': 'cltbld', 01:41:25 INFO - 'MAIL': '/var/mail/cltbld', 01:41:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:41:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:25 INFO - 'MOZ_NO_REMOTE': '1', 01:41:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:25 INFO - 'NO_EM_RESTART': '1', 01:41:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:25 INFO - 'PWD': '/builds/slave/test', 01:41:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:41:25 INFO - 'SHELL': '/bin/bash', 01:41:25 INFO - 'SHLVL': '1', 01:41:25 INFO - 'TERM': 'linux', 01:41:25 INFO - 'TMOUT': '86400', 01:41:25 INFO - 'USER': 'cltbld', 01:41:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933', 01:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:25 INFO - '_': '/tools/buildbot/bin/python'} 01:41:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 01:41:25 INFO - mozversion application_buildid: 20160718223049 01:41:25 INFO - mozversion application_changeset: df35aa6dffb62d73cbc404094e2cf5ee147bf587 01:41:25 INFO - mozversion application_display_name: Nightly 01:41:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:41:25 INFO - mozversion application_name: Firefox 01:41:25 INFO - mozversion application_remotingname: firefox 01:41:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:41:25 INFO - mozversion application_vendor: Mozilla 01:41:25 INFO - mozversion application_version: 50.0a1 01:41:25 INFO - mozversion platform_buildid: 20160718223049 01:41:25 INFO - mozversion platform_changeset: df35aa6dffb62d73cbc404094e2cf5ee147bf587 01:41:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:41:25 INFO - mozversion platform_version: 50.0a1 01:41:25 INFO - using testdate: 1468917685 01:41:25 INFO - actual date: 1468917685 01:41:25 INFO - starting webserver on 'localhost:35168' 01:41:25 INFO - SUITE-START | Running 2 tests 01:41:25 INFO - TEST-START | dromaeo_css 01:41:25 INFO - operating with platform_type : linux_ 01:41:25 INFO - Initialising browser for dromaeo_css test... 01:41:25 INFO - TEST-INFO | started process 12936 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDUkSZz/profile http://localhost:35168/getInfo.html) 01:41:29 INFO - PROCESS | 12936 | __metrics Screen width/height:1600/1200 01:41:29 INFO - PROCESS | 12936 | colorDepth:24 01:41:29 INFO - PROCESS | 12936 | Browser inner width/height: 1024/697 01:41:29 INFO - PROCESS | 12936 | __metrics 01:41:30 INFO - PROCESS | 12936 | 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" 01:41:30 INFO - PROCESS | 12936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:30 INFO - TEST-INFO | 12936: exit 0 01:41:30 INFO - Browser initialized. 01:41:30 INFO - Running cycle 1/1 for dromaeo_css test... 01:41:30 INFO - TEST-INFO | started process 13016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDUkSZz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:42 INFO - PROCESS | 13016 | RSS: Main: 157937664 01:41:42 INFO - PROCESS | 13016 | 01:42:34 INFO - PROCESS | 13016 | 1468917754217 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] 01:44:42 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35168/tests/dromaeo/cssquery-ext.html) 01:44:43 INFO - PROCESS | 13016 | RSS: Main: 257159168 01:44:43 INFO - PROCESS | 13016 | 01:47:45 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-ext.html (next: http://localhost:35168/tests/dromaeo/cssquery-jquery.html) 01:47:46 INFO - PROCESS | 13016 | RSS: Main: 238813184 01:47:46 INFO - PROCESS | 13016 | 01:50:47 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35168/tests/dromaeo/cssquery-mootools.html) 01:50:48 INFO - PROCESS | 13016 | RSS: Main: 241016832 01:50:48 INFO - PROCESS | 13016 | 01:53:50 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35168/tests/dromaeo/cssquery-prototype.html) 01:53:51 INFO - PROCESS | 13016 | RSS: Main: 232427520 01:53:51 INFO - PROCESS | 13016 | 01:56:53 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35168/tests/dromaeo/cssquery-yui.html) 01:56:54 INFO - PROCESS | 13016 | RSS: Main: 212594688 01:56:54 INFO - PROCESS | 13016 | 01:59:56 INFO - PROCESS | 13016 | Cycle 1(1): loaded http://localhost:35168/tests/dromaeo/cssquery-yui.html (next: http://localhost:35168/tests/dromaeo/cssquery-dojo.html) 01:59:57 INFO - PROCESS | 13016 | RSS: Main: 282894336 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | __start_tp_report 01:59:57 INFO - PROCESS | 13016 | _x_x_mozilla_page_load 01:59:57 INFO - PROCESS | 13016 | _x_x_mozilla_page_load_details 01:59:57 INFO - PROCESS | 13016 | |i|pagename|runs| 01:59:57 INFO - PROCESS | 13016 | |0;dojo.html;3078.9692103078964;3099.550565168052;3126.7498600111994;3130.2487403023265;3145.9685403145977;5347.144456886899;5398.13629819229;5410.377806552247;5536.474034966682;5543.25166102576;5410.269613602162;5413.918791218135;5462.0441422750955;5501.284832971718;5519.144532597444;4857.902841943168;4887.0940333270055;4929.5316944890355;4939.950600494003;5061.519155680205;4939.407271127469;4978.5270399312185;5003.749812509378;5020.7991680332925;5033.270175824503;4853.247746599282;4896.167651499253;4897.461279259296;4918.139325618038;4930.6795058321095;4887.0714564232685;4917.901641967176;4980.87547811304;4981.900361992776;5035.672681275705;4852.175130227834;4961.08219979305;4961.231009193581;4982.975085124587;4995.5753760930365;5326.7869285228935;5404.864878378033;5425.050616142159;5474.589405794582;5513.8345849624575;5851.034579294412;5855.238818953509;5876.08920617305;5907.379725128885;5913.704314784243;5640.8871822563315;5903.734331955073;5954.55340849438;5955.761769529214;5956.255468066492;5450.482204190595;5490.368607610136;5513.889722205534;5580.972095139498;5586.664800112007;6052.530836883904;6061.2994041164075;6067.893628015979;6109.083637454418;6121.846953826101;5486.890262194734;5671.716414179275;5855.033919403336;5855.707214639251;5869.383714709979;5804.332501762267;5898.616589921641;5911.940880591225;5913.793017244376;5916.408359164136;5778;5812.564057695689;5825.33008703996;5834.970825145847;5855.414458554111;5550.222968784374;5763.106718458645;5821.88356232873;5858.56060795442;5865.88268234633;4439.42287502629;4440.4227450431235;4450.376947227391;4478.283474644041;4478.462584489882;3970;3985.0236692010644;3990.3016972029777;4017.859374921922;4019.979900100481;5386.434424385383;5436.537894278951;5529.474699903502;5562.499375056264;5593.244911936837;5948.970255148696;5966.463018328284;5972.074328479092;5983.042713165873;6021.819345419644;5615.6630602164005;5624.97187514068;5624.97187514068;5625.746841392147;5626.971865140648;4838.661293709407;5042.899142017139;5089.618278629117;5100.515451032146;5107.540321371094;6078.209832721718;6103.3591472894705;6107.480864126599;6112.113743507255;6112.908306375408;5921.171036055042;5954.255718035246;5959.791407300897;5963.4334738199805;5964.612300200473;5849.239598852292;5856.472917437451;5868.589198756046;5892.5285977122585;5974.970125149346;5660.7169641518585;5688.11834165705;5690.886182276414;5694.857628559319;5697.173909782975;5710.314762228559;5742.856428589319;5810.128480727924;5812.982728022664;5814.825555233349;5682.062459694104;5736.770529178786;5738;5760;5769.74036168365;5763.6541807491685;5765.971170144123;5770.163326317576;5774.105013722879;5774.422557744192;5431.972840135774;5597.888042239214;5696.430356964436;5706.971465142814;5709.429057094423;5752.1659359391815;5805.013147764806;5815.709214539341;5856.677882716492;5871.354151043467;5651.886962260814;5670;5677.77288908439;5685.175649530877;5690.032694441872 01:59:57 INFO - PROCESS | 13016 | |1;ext.html;606.1211243696638;612.5099920063949;628.0735914526075;632.3960617610181;651.3486513486514;33093.17267068321;35963.460548091796;35975.640243597554;43837.78081109598;44944.32583511242;46778.53221467793;47107.52892471083;47240.527594724044;47353.28970065452;47376;8909.242714369286;9020.77448063797;9053.818923621524;9058;9062.320325975545;8245.216704413071;8426.957865210665;8446.873296900552;8659.870101948476;9043.728688139334;10885.945570272137;11168;11182.66452006437;11487.770244595104;11505.022073123795;11026.724331891686;11110.222284440093;11113.666590002313;11118.166137539676;11127.33236005834;8230.753077407688;10481.842772358421;10798.73003174919;10887.965643263957;10890.128789696806;43950.560494395286;44433.33349999753;44454.333185001924;44482.55517444816;44527.77736111331;3563.6436356364156;3679.797611131387;4015.8795236142964;4287.742735435884;4366.737995720236;31478.527822082688;33487.66512334894;33702.66297337044;34192.14519637004;34270.828645856614;3983.820728067244;4014.658754005913;4040.1313717550574;4090.1001779608737;4100.302948498733;339427;339458;340560;342011;342603;55622.443775561725;55834.72082639642;56026.879462411336;56100.43899561034;56187.43812561904;64450;64775.35224647787;64778;64787.352126479076;64909;17447.389341373244;17893.821061789215;17969.460916172535;17972.9101354495;17994.820051799314;13794.723870561445;13860.792088118686;13865.168089914638;13873.653158670912;13879.86120138806;17573.912130439523;19412.902935485232;19609.901950490155;19682.70475942862;19788.802111978985;7289.271072892774;7304.671289791972;7446.367058800062;7552.39580833532;7703.1911649277135;988.7429268390271;1065.94137322448;1071.749326414539;1134.495149658394;1173.1904985559936;33685.32629347399;34098.14754631154;34249.97250082502;34551.030823122404;34654.653453465144;1087.7280679830042;1135.40391294571;1139.0830381542771;1151.0561339701364;1203.1577895473204;16982.32070717182;17787.822121778874;17789.859590493263;17888.28446862111;18012.729809052875;594260;917703;929838;930306;930654;61325.38674613286;61647;62083.379166208666;62148.68925655343;62187.37812621906;1253.1729058821134;1334.646318725519;1388.6667199871958;1406.1141480867193;1412.69627030189;32496.675033249365;34343.48484772781;34807.82596087003;35049;35065.824670877504;50929;51059.74470127626;51475.742621286656;51593.742031291105;51723.44829655116;451890;455080;455404;457089;458299;24730.50538989246;25479.61780573256;26077.608835867857;26168;28765.28086797847;43413.565864340955;44902.32646510239;48428.51571484381;49408.25887611761;49586.25620615621;40605;40845.59154408418;41240.587594124874;41345.586544135374;42005.579944200166;40248.798756006036;40739.592604074765;41371.793141034104;41635.375469367376;42072.579274206866;40979.180416392104;41438.1712365745;42815.78592107019;43675.12649747051;43832;36733.81633091818;38585;38965.80517097397;39378;39609.80195099006;29272.853635731684;29448.558271625516;29551;29699.703002969694;30077 01:59:57 INFO - PROCESS | 13016 | |2;jquery.html;393.79129061597325;401.38788347769645;402.47477042459576;405.2442195305755;414.3508834837347;18378.172982215798;18884.905575472105;19921.203151873906;20064.097115629796;20076.598468030632;18921.337753178643;19052.047397630096;20304.69542956857;20415;20549.69175462369;5507.807226747064;5652.689102099373;5801.767929282823;5811;5888.175655408247;5732.570057245703;5760.971195144019;5773.047447171211;5778.393268706788;5781.39295373986;6537.346265373472;6550.443212326958;6563.278039415661;6568.1789776277965;6570.211574611053;5856.502197313233;5864.76540938362;6098.7560497579825;6534.509911756637;6536.934630653704;5607.467290607386;5616.32604087508;5625.212470254127;5634.802781902655;5651.265335506399;14162.575122746333;20250.594988100303;22820.261568611873;23342.416439588975;23921.16275930334;2226.9109235630553;2453.9754602453927;2504.8998040078377;2558.884850181728;2915.8833646654107;9384.624615015391;10131.493425328705;10665.733356666145;10891.83662245067;10932.234743567953;1964.2143142742875;1967.79338169493;2016.062695362159;2031.9898400507902;2053.5460125150453;84997;87242.5637871813;87816;88839;88974.55512722395;10253.128484078938;10694.572217111228;10710.357378557312;10742.248042637097;11114.11087113029;14590.270486475634;14623.415063397344;14626;14649.194294313915;14671.853281467262;7967.521948683097;8049.3962952778775;8080.353571714366;8084;8169.183081691902;7976.162502937224;8009.4393392463135;8017.559034253172;8107.432479726481;8127.309178719756;4449.57729015743;4503.909921801545;4513.774311284423;4533.818647254073;4581.541845815392;1819.954501137482;1846.3260909767837;1903.6763750162506;1918.9712154317697;1936.7772706138692;983.7555492302243;1028.4703377760459;1036.9170466362823;1046.5342922399611;1081.1783044885785;19222.327218547285;20007.099680514413;20331.18675253003;20357.898210508854;20438.59122817535;980.0353929363732;1009.3792317724693;1012.7844094998351;1055.3456856748712;1067.0556557446716;10498.370097794157;11069.225154239288;11117.332960022424;11152.832707509393;11167.944160279147;506226;508982;526642;528596;529852;93410;95585;96371;98125.2639685962;100951.49524252479;954.398728800851;968.2302569457157;974.2011550528499;983.2822039910905;989.3074847606704;17436.90757109155;17470.825291747427;17528;17559.297628094733;17893.821061789215;44274.114517710106;44982.325265121704;45723.771381142884;45833;46200.07599848051;56877;56955.43044569667;62971.685141574;63768.3623163781;64197.67901160464;8057.798555036171;8293.087760346478;8350.874736879074;8442.915570844212;8489.872651910102;12053.51785928553;13114.344282785632;13623.18260904349;13918.304084795518;14474.565763027125;11715.707107322385;12117.394130293274;12137.332446715161;12169;12547.498100075894;11408;11543.653690389301;11820.76358472843;12058.216215945935;12204.938975305067;11368.261063030877;11758.88241117612;11989.1008174389;12053.457594408097;12345.197562158432;8334.541600211804;8681.744345870298;8720.781980450285;8885.111488851064;8907.821843562962;10724.464968469016;11761.882381176421;11781.293122412682;12208.572699955459;12310.446029928495 01:59:57 INFO - PROCESS | 13016 | |3;mootools.html;1348.7504811609858;1495.2374289112552;1525.1611613612513;1681.747737839324;1814.9364772232975;8119.188081191885;8427.494350338984;8436.072032076474;8684.08817074206;8688.348373871953;8442.831143377143;8606.784830379243;8617.095205003478;8624.741257762262;8629.870551941725;2050.5078781092507;2289.2331069091874;2336.2056900653733;2361.3388251289666;2368.786809187173;2407.590709579376;2410.337157281744;2418.4074901649033;2431.7932975697086;2436.049940523197;2311.6879221305076;2368.218487898989;2370.8221883358733;2372.0748907925836;2372.9525409491885;1994.760628724555;2192.3422973108004;2273.943151421211;2279.4073540879326;2336.147306233221;2311.2719493359537;2331.3239160643393;2332.2536788227776;2332.661764044204;2335.8364914455997;9151.542422878829;9205.769855753593;9227.400218985744;9261.073892610751;9273.629054837798;5364.463553644604;5483.396826349099;5497.945020549823;5499.972500137474;5501.119820828648;3272.803631782077;3306.718928891047;3349.815760133192;3365.125067482448;3392.9830350848333;936.3211671538148;953.0564740906522;956.7368973532265;962.7978124593843;964.1419136968104;12280;12582.370881455981;12612.117151799377;12932.418041188263;12934.159279646792;4511.255642818964;4609.1703493371515;4670.042641258534;4691.577758001797;4731.195696731564;9961.203103751683;9982.450965196984;9992.25058120644;9993.800123997626;10000.099991000845;5423.2136340230445;5425.864353391118;5428.864278393072;5431.538319242909;5449.128139497661;4440.156370289635;4892.119418504703;5012.24816277554;5025.246213068069;5090.41460232078;3764.153065560282;3832.2143960488033;3879.6702280305926;3946.723729338919;3953.110550126256;1042.468341146021;1093.6609651008214;1117;1123.2474242257706;1136.880627534097;929.693201243588;1007.315025782475;1022.8568000479938;1024.938503689781;1025.7179275699243;6128.356522565066;6137.662428566472;6145.231846019247;6254.093156492374;6322.810315690536;986.6152200641756;988.5172241637543;1003.5835128421769;1017.877854657431;1026.8716410448694;4267.295896177158;4396.538363471854;4418.668599855024;4450.977745111254;4455.68810183284;10878.782424351468;11302.773944521063;11318.490667919963;11335.773284534429;11348.716282093013;10661.520231589597;11121.165912556591;11125.72185695348;11262.380569068617;11458.77082458363;8337.374696897636;8447.493150410995;8451.197136272045;8462.407631465738;8468.40721149526;6249.875002500015;6265.843353916189;6435.646039467874;6441.935580644321;6527.314631963765;10059.19526437898;10689;10693.465326733787;10709.303895247096;10808.243422960632;10542.156627469938;10766.407847568145;10780.29928054674;10797.406142661916;10877.782444351227;7985.800354991172;8053.476524025919;8076.757697268857;8094.433389662667;8185.8772118419465;8858.202761751216;8864;8881.911180888366;8929.553522323988;8932.687355942771;7986.7603971880935;8114.310283625958;8192.795180120545;8199.098099208963;8209.179082091743;7843.960780196063;7939.801504962191;7968.721094761656;8052.074011488754;8114.634841432267;6874.725010999504;6920.757773478105;6946.722131114901;6971.65141742921;6980.511364204457;5291.365036195682;5379.919301210563;5519.420460851553;5556.305461817273;5615.073512870331;5403.702796346081;5533.2253484513;5605.299337582802;5677.77288908439;5694.4590263924865 01:59:57 INFO - PROCESS | 13016 | |4;prototype.html;327.9344131173766;330.36404920527985;337.2698108594892;337.3556507071491;337.989860304191;5203.167493201088;5302.867428314285;5468.316460442445;5519.779208831647;5530.4746049125315;5293.206019097137;5424.674519528831;5436.103042997901;5439.048166570854;5473.343198816147;4251.256030194719;4258.318669012959;4261.254280500915;4265.616094551489;4282.079352939118;4017.7589344639414;4017.959820401788;4211.536730959592;4279.443672322594;4282.97858510707;4196.685248606352;4202.915941681164;4208.663306935453;4209.85265515707;4213.683973701969;4188.1204946961025;4189.455370801791;4198.769067701275;4201.432806571104;4217.683673724483;3606.4049431843714;3687.3547129252274;3700.833462494167;3702.3520883845217;3715.7584756990977;5216.921746173811;5223.399309079428;5260.395054568697;5271.103912334874;5278.392984806719;4760.381150450454;4780.904381912377;4793.808247670089;4819.807207711688;4827.758612069382;5387.865303367408;5567;5612.5126841386655;5626.915596266018;5636.2954630671165;3940.349842276021;3944.7041471889725;4006.2988976929214;4009.3985902114623;4043.413705012786;5759.625624334447;5767.538596912237;5785.644326290741;5804.477597016289;5804.810013947131;3941.625545573166;4104.938425923614;4120.979395103065;4123.60825721556;4129.483814523184;4184.91630167401;4191.077962848169;4194.748315101104;4200.285951388271;4201.327787553977;4855.6601037927;4858.61529464101;4865.245886887538;4868.975655121772;4874.073925954128;5536.8062117826485;5692.231548740867;5729.541636669056;5766.163906233573;5778.8266352009505;1430.8926830487756;1436.77011678131;1445.3640398224748;1445.9566213013627;1450.100937418807;903.421810041574;929.3215952354684;940.2478017585904;940.2853831088422;945.5603144537686;4877.780499877514;4877.804937790193;4888.706677599355;4921.606271498272;4935.457099719028;5120.308758317654;5132.204508301219;5136.306598609214;5197.714125723046;5205.453427390145;4712;4828.66199366048;4830.734309613005;4915.132249745074;4991.775370108354;2781.902633407821;2800.103966730627;2805.368792021779;2820.5615136280235;2839.799155654343;5239.266502689627;5341.866453338697;5360.222767698662;5418.8916221675345;5419.295491586068;5266.815661451831;5337.224643185522;5570.498655121059;6031.517478601701;6038.698065096728;6018.759249629966;6024.397560244028;6041.003234466351;6050.6369617823075;6066.178307666142;5624.581429985145;5911.852203694942;5916.437938396018;5937.673427961504;5944.494717949023;5773.653580785166;5779.595428319977;5785.595008349375;5805.477507024219;5819.5053420459735;5989.401059893976;5991.101334799815;5997.760089596367;6056.636601803906;6075.544334174866;3853.8843834685003;3941.467901833216;4069.104796944667;4080.4491393661474;4090.6318431341324;5819.621724587888;5828.737706803119;5844.211031510862;5846.35690074083;5894.941050589439;5605.1872478491205;5652.152177173457;5669.631473954345;5749.786332262026;5766.654000759968;5615.522680572034;5626.803061892814;5630.971845140748;5634.211210430625;5681.971590142188;5553.888922221614;5566.582506311962;5624.803131890528;5638;5642.210090587403;5606.271184745957;5649.265595472562;5681.119426488901;5692.743826527733;5762.596618236683;5531.917021244604;5569.989607482606;5575.470330318613;5597.748101335368;5616.9327827712605 01:59:57 INFO - PROCESS | 13016 | |5;yui.html;770.3682979956435;787.2678409079557;810.5947026486757;818.4148333941233;851.0468275531406;15756.84243157568;16860.325586976505;16881.071541065252;16883.83116168838;16909.323627054917;14549.127052376838;14615.12309261445;14633.122012679247;14635.926820365883;14638.926805365985;664.5547483186277;809.6356639512213;836.0385556609917;839.1985653700732;843.3590471241839;830.730012745857;838.9916100839005;839.5340585974782;841.6549214821924;841.9957900210491;697.4350775871535;884.907084756101;886.5965985476593;886.6719313853883;888.0675290944482;851.8849955256054;869.8303830753006;870.9695160669346;879.6261588824788;880.9559522023873;638.7636574467476;646.1954866191575;646.444058110025;646.612032780328;647.0100745858844;15479.14864682442;15502;15533.14567698776;15537;15541.689166216725;14249.928750356183;14753.409863605442;14790.70418591633;14807.111573305529;14875.330610122463;11334.943325283402;11657.883421165849;12079.698007549794;13368.86631133686;13681.931590342085;4296.74855455347;4345.348197770328;4369.038811461473;4407.162639098593;4430.246858034142;62718.68640656768;62997.370026299606;71036;74473.62763186204;75116.24883751148;11773.17587768865;11796.58711945095;11842.052635789116;11994.280343179436;12002.819957700642;20456.795432045787;20594.24948879332;20594.27920022822;20606.484837879172;20668.793312066988;8631.525266110299;8634.093420190915;8638.049814520398;8643.827123457495;8731.170538798804;4852.126617208936;4887.340209071801;4953.0589188054155;4967.776450059756;5059.165237735732;3435.811030393352;3512.8594856205978;3524.400851855191;3532.293541291752;3543.6279190685123;631.1321932343028;642.8842808294552;650.9446697030805;658.3383699382186;659.0476761080289;588.7880363069291;637.4914710613657;639.389383139101;671.8992151177265;688.0745397440435;9190.356675032683;9530.857037144448;9579.90420095804;9834.803303934024;10251.84622230667;682.2256738601723;696.9581825090511;707.9115110611174;718.2422544215798;734.2143906020614;4288.163808057461;4451.311298680041;4542.909141817211;4572.108438854458;4577.679562430598;66038.33961660322;81598;113901;115442.42278788552;115544.42227788975;99003.50498247462;99010;100493;100708.49645751725;101318;66651;86439.1356086431;87825.56087219651;88521.55739221392;89053;8489.320854331761;8529.616167272612;8668.34987375962;8772.692955746517;9001.684941027295;52710.73644631906;52749.20876186937;52776.736116319174;53834.730826345614;53942;66614;67315;71178;71727.28272717414;73904.63047684728;10534.628680414902;10568.89431105679;10759.3544387337;10811.567537298735;10969.50637221311;12675.30285834288;12724.618261452171;12944.093913426346;13032.869671303166;13049.804252936403;12144.939275303921;12210.63368098923;12250;12504.874951250735;13215.60353189406;12497.500099995897;12633.747325053631;12655.746885062432;12915.289659068842;13316.067875249028;12184.939075304566;12369.93815030919;12499.500019999461;12842.101052926206;13010.024248181235;10183;10328.431936243578;10724.410157441416;10735.248532602644;10977.28647637901;10177.185825133824;10643.574257029632;10644;10672.57309707603;10701.518431670438 01:59:57 INFO - PROCESS | 13016 | __end_tp_report 01:59:57 INFO - PROCESS | 13016 | __start_cc_report 01:59:57 INFO - PROCESS | 13016 | _x_x_mozilla_cycle_collect,858 01:59:57 INFO - PROCESS | 13016 | __end_cc_report 01:59:57 INFO - PROCESS | 13016 | __startTimestamp1468918797179__endTimestamp 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | ------- Summary: start ------- 01:59:57 INFO - PROCESS | 13016 | Number of tests: 6 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#0] dojo.html Cycles:165 Average:5437.19 Median:5670.00 stddev:633.06 (11.2%) stddev-sans-first:607.37 01:59:57 INFO - PROCESS | 13016 | Values: 3079.0 3099.6 3126.7 3130.2 3146.0 5347.1 5398.1 5410.4 5536.5 5543.3 5410.3 5413.9 5462.0 5501.3 5519.1 4857.9 4887.1 4929.5 4940.0 5061.5 4939.4 4978.5 5003.7 5020.8 5033.3 4853.2 4896.2 4897.5 4918.1 4930.7 4887.1 4917.9 4980.9 4981.9 5035.7 4852.2 4961.1 4961.2 4983.0 4995.6 5326.8 5404.9 5425.1 5474.6 5513.8 5851.0 5855.2 5876.1 5907.4 5913.7 5640.9 5903.7 5954.6 5955.8 5956.3 5450.5 5490.4 5513.9 5581.0 5586.7 6052.5 6061.3 6067.9 6109.1 6121.8 5486.9 5671.7 5855.0 5855.7 5869.4 5804.3 5898.6 5911.9 5913.8 5916.4 5778.0 5812.6 5825.3 5835.0 5855.4 5550.2 5763.1 5821.9 5858.6 5865.9 4439.4 4440.4 4450.4 4478.3 4478.5 3970.0 3985.0 3990.3 4017.9 4020.0 5386.4 5436.5 5529.5 5562.5 5593.2 5949.0 5966.5 5972.1 5983.0 6021.8 5615.7 5625.0 5625.0 5625.7 5627.0 4838.7 5042.9 5089.6 5100.5 5107.5 6078.2 6103.4 6107.5 6112.1 6112.9 5921.2 5954.3 5959.8 5963.4 5964.6 5849.2 5856.5 5868.6 5892.5 5975.0 5660.7 5688.1 5690.9 5694.9 5697.2 5710.3 5742.9 5810.1 5813.0 5814.8 5682.1 5736.8 5738.0 5760.0 5769.7 5763.7 5766.0 5770.2 5774.1 5774.4 5432.0 5597.9 5696.4 5707.0 5709.4 5752.2 5805.0 5815.7 5856.7 5871.4 5651.9 5670.0 5677.8 5685.2 5690.0 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#1] ext.html Cycles:180 Average:70230.84 Median:22132.34 stddev:162014.36 (732.0%) stddev-sans-first:162384.52 01:59:57 INFO - PROCESS | 13016 | Values: 606.1 612.5 628.1 632.4 651.3 33093.2 35963.5 35975.6 43837.8 44944.3 46778.5 47107.5 47240.5 47353.3 47376.0 8909.2 9020.8 9053.8 9058.0 9062.3 8245.2 8427.0 8446.9 8659.9 9043.7 10885.9 11168.0 11182.7 11487.8 11505.0 11026.7 11110.2 11113.7 11118.2 11127.3 8230.8 10481.8 10798.7 10888.0 10890.1 43950.6 44433.3 44454.3 44482.6 44527.8 3563.6 3679.8 4015.9 4287.7 4366.7 31478.5 33487.7 33702.7 34192.1 34270.8 3983.8 4014.7 4040.1 4090.1 4100.3 339427.0 339458.0 340560.0 342011.0 342603.0 55622.4 55834.7 56026.9 56100.4 56187.4 64450.0 64775.4 64778.0 64787.4 64909.0 17447.4 17893.8 17969.5 17972.9 17994.8 13794.7 13860.8 13865.2 13873.7 13879.9 17573.9 19412.9 19609.9 19682.7 19788.8 7289.3 7304.7 7446.4 7552.4 7703.2 988.7 1065.9 1071.7 1134.5 1173.2 33685.3 34098.1 34250.0 34551.0 34654.7 1087.7 1135.4 1139.1 1151.1 1203.2 16982.3 17787.8 17789.9 17888.3 18012.7 594260.0 917703.0 929838.0 930306.0 930654.0 61325.4 61647.0 62083.4 62148.7 62187.4 1253.2 1334.6 1388.7 1406.1 1412.7 32496.7 34343.5 34807.8 35049.0 35065.8 50929.0 51059.7 51475.7 51593.7 51723.4 451890.0 455080.0 455404.0 457089.0 458299.0 24730.5 25479.6 26077.6 26168.0 28765.3 43413.6 44902.3 48428.5 49408.3 49586.3 40605.0 40845.6 41240.6 41345.6 42005.6 40248.8 40739.6 41371.8 41635.4 42072.6 40979.2 41438.2 42815.8 43675.1 43832.0 36733.8 38585.0 38965.8 39378.0 39609.8 29272.9 29448.6 29551.0 29699.7 30077.0 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#2] jquery.html Cycles:180 Average:30386.37 Median:2531.89 stddev:85859.91 (3391.1%) stddev-sans-first:86071.23 01:59:57 INFO - PROCESS | 13016 | Values: 393.8 401.4 402.5 405.2 414.4 18378.2 18884.9 19921.2 20064.1 20076.6 18921.3 19052.0 20304.7 20415.0 20549.7 5507.8 5652.7 5801.8 5811.0 5888.2 5732.6 5761.0 5773.0 5778.4 5781.4 6537.3 6550.4 6563.3 6568.2 6570.2 5856.5 5864.8 6098.8 6534.5 6536.9 5607.5 5616.3 5625.2 5634.8 5651.3 14162.6 20250.6 22820.3 23342.4 23921.2 2226.9 2454.0 2504.9 2558.9 2915.9 9384.6 10131.5 10665.7 10891.8 10932.2 1964.2 1967.8 2016.1 2032.0 2053.5 84997.0 87242.6 87816.0 88839.0 88974.6 10253.1 10694.6 10710.4 10742.2 11114.1 14590.3 14623.4 14626.0 14649.2 14671.9 7967.5 8049.4 8080.4 8084.0 8169.2 7976.2 8009.4 8017.6 8107.4 8127.3 4449.6 4503.9 4513.8 4533.8 4581.5 1820.0 1846.3 1903.7 1919.0 1936.8 983.8 1028.5 1036.9 1046.5 1081.2 19222.3 20007.1 20331.2 20357.9 20438.6 980.0 1009.4 1012.8 1055.3 1067.1 10498.4 11069.2 11117.3 11152.8 11167.9 506226.0 508982.0 526642.0 528596.0 529852.0 93410.0 95585.0 96371.0 98125.3 100951.5 954.4 968.2 974.2 983.3 989.3 17436.9 17470.8 17528.0 17559.3 17893.8 44274.1 44982.3 45723.8 45833.0 46200.1 56877.0 56955.4 62971.7 63768.4 64197.7 8057.8 8293.1 8350.9 8442.9 8489.9 12053.5 13114.3 13623.2 13918.3 14474.6 11715.7 12117.4 12137.3 12169.0 12547.5 11408.0 11543.7 11820.8 12058.2 12204.9 11368.3 11758.9 11989.1 12053.5 12345.2 8334.5 8681.7 8720.8 8885.1 8907.8 10724.5 11761.9 11781.3 12208.6 12310.4 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#3] mootools.html Cycles:180 Average:5945.43 Median:5018.75 stddev:3402.52 (67.8%) stddev-sans-first:3394.53 01:59:57 INFO - PROCESS | 13016 | Values: 1348.8 1495.2 1525.2 1681.7 1814.9 8119.2 8427.5 8436.1 8684.1 8688.3 8442.8 8606.8 8617.1 8624.7 8629.9 2050.5 2289.2 2336.2 2361.3 2368.8 2407.6 2410.3 2418.4 2431.8 2436.0 2311.7 2368.2 2370.8 2372.1 2373.0 1994.8 2192.3 2273.9 2279.4 2336.1 2311.3 2331.3 2332.3 2332.7 2335.8 9151.5 9205.8 9227.4 9261.1 9273.6 5364.5 5483.4 5497.9 5500.0 5501.1 3272.8 3306.7 3349.8 3365.1 3393.0 936.3 953.1 956.7 962.8 964.1 12280.0 12582.4 12612.1 12932.4 12934.2 4511.3 4609.2 4670.0 4691.6 4731.2 9961.2 9982.5 9992.3 9993.8 10000.1 5423.2 5425.9 5428.9 5431.5 5449.1 4440.2 4892.1 5012.2 5025.2 5090.4 3764.2 3832.2 3879.7 3946.7 3953.1 1042.5 1093.7 1117.0 1123.2 1136.9 929.7 1007.3 1022.9 1024.9 1025.7 6128.4 6137.7 6145.2 6254.1 6322.8 986.6 988.5 1003.6 1017.9 1026.9 4267.3 4396.5 4418.7 4451.0 4455.7 10878.8 11302.8 11318.5 11335.8 11348.7 10661.5 11121.2 11125.7 11262.4 11458.8 8337.4 8447.5 8451.2 8462.4 8468.4 6249.9 6265.8 6435.6 6441.9 6527.3 10059.2 10689.0 10693.5 10709.3 10808.2 10542.2 10766.4 10780.3 10797.4 10877.8 7985.8 8053.5 8076.8 8094.4 8185.9 8858.2 8864.0 8881.9 8929.6 8932.7 7986.8 8114.3 8192.8 8199.1 8209.2 7844.0 7939.8 7968.7 8052.1 8114.6 6874.7 6920.8 6946.7 6971.7 6980.5 5291.4 5379.9 5519.4 5556.3 5615.1 5403.7 5533.2 5605.3 5677.8 5694.5 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#4] prototype.html Cycles:180 Average:4687.85 Median:5268.96 stddev:1388.24 (26.3%) stddev-sans-first:1353.02 01:59:57 INFO - PROCESS | 13016 | Values: 327.9 330.4 337.3 337.4 338.0 5203.2 5302.9 5468.3 5519.8 5530.5 5293.2 5424.7 5436.1 5439.0 5473.3 4251.3 4258.3 4261.3 4265.6 4282.1 4017.8 4018.0 4211.5 4279.4 4283.0 4196.7 4202.9 4208.7 4209.9 4213.7 4188.1 4189.5 4198.8 4201.4 4217.7 3606.4 3687.4 3700.8 3702.4 3715.8 5216.9 5223.4 5260.4 5271.1 5278.4 4760.4 4780.9 4793.8 4819.8 4827.8 5387.9 5567.0 5612.5 5626.9 5636.3 3940.3 3944.7 4006.3 4009.4 4043.4 5759.6 5767.5 5785.6 5804.5 5804.8 3941.6 4104.9 4121.0 4123.6 4129.5 4184.9 4191.1 4194.7 4200.3 4201.3 4855.7 4858.6 4865.2 4869.0 4874.1 5536.8 5692.2 5729.5 5766.2 5778.8 1430.9 1436.8 1445.4 1446.0 1450.1 903.4 929.3 940.2 940.3 945.6 4877.8 4877.8 4888.7 4921.6 4935.5 5120.3 5132.2 5136.3 5197.7 5205.5 4712.0 4828.7 4830.7 4915.1 4991.8 2781.9 2800.1 2805.4 2820.6 2839.8 5239.3 5341.9 5360.2 5418.9 5419.3 5266.8 5337.2 5570.5 6031.5 6038.7 6018.8 6024.4 6041.0 6050.6 6066.2 5624.6 5911.9 5916.4 5937.7 5944.5 5773.7 5779.6 5785.6 5805.5 5819.5 5989.4 5991.1 5997.8 6056.6 6075.5 3853.9 3941.5 4069.1 4080.4 4090.6 5819.6 5828.7 5844.2 5846.4 5894.9 5605.2 5652.2 5669.6 5749.8 5766.7 5615.5 5626.8 5631.0 5634.2 5682.0 5553.9 5566.6 5624.8 5638.0 5642.2 5606.3 5649.3 5681.1 5692.7 5762.6 5531.9 5570.0 5575.5 5597.7 5616.9 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - PROCESS | 13016 | [#5] yui.html Cycles:180 Average:19863.15 Median:4574.89 stddev:28262.00 (617.8%) stddev-sans-first:28304.92 01:59:57 INFO - PROCESS | 13016 | Values: 770.4 787.3 810.6 818.4 851.0 15756.8 16860.3 16881.1 16883.8 16909.3 14549.1 14615.1 14633.1 14635.9 14638.9 664.6 809.6 836.0 839.2 843.4 830.7 839.0 839.5 841.7 842.0 697.4 884.9 886.6 886.7 888.1 851.9 869.8 871.0 879.6 881.0 638.8 646.2 646.4 646.6 647.0 15479.1 15502.0 15533.1 15537.0 15541.7 14249.9 14753.4 14790.7 14807.1 14875.3 11334.9 11657.9 12079.7 13368.9 13681.9 4296.7 4345.3 4369.0 4407.2 4430.2 62718.7 62997.4 71036.0 74473.6 75116.2 11773.2 11796.6 11842.1 11994.3 12002.8 20456.8 20594.2 20594.3 20606.5 20668.8 8631.5 8634.1 8638.0 8643.8 8731.2 4852.1 4887.3 4953.1 4967.8 5059.2 3435.8 3512.9 3524.4 3532.3 3543.6 631.1 642.9 650.9 658.3 659.0 588.8 637.5 639.4 671.9 688.1 9190.4 9530.9 9579.9 9834.8 10251.8 682.2 697.0 707.9 718.2 734.2 4288.2 4451.3 4542.9 4572.1 4577.7 66038.3 81598.0 113901.0 115442.4 115544.4 99003.5 99010.0 100493.0 100708.5 101318.0 66651.0 86439.1 87825.6 88521.6 89053.0 8489.3 8529.6 8668.3 8772.7 9001.7 52710.7 52749.2 52776.7 53834.7 53942.0 66614.0 67315.0 71178.0 71727.3 73904.6 10534.6 10568.9 10759.4 10811.6 10969.5 12675.3 12724.6 12944.1 13032.9 13049.8 12144.9 12210.6 12250.0 12504.9 13215.6 12497.5 12633.7 12655.7 12915.3 13316.1 12184.9 12369.9 12499.5 12842.1 13010.0 10183.0 10328.4 10724.4 10735.2 10977.3 10177.2 10643.6 10644.0 10672.6 10701.5 01:59:57 INFO - PROCESS | 13016 | -------- Summary: end -------- 01:59:57 INFO - PROCESS | 13016 | 01:59:57 INFO - TEST-INFO | 13016: exit 0 01:59:57 INFO - TEST-OK | dromaeo_css | took 1112483ms 01:59:57 INFO - TEST-START | kraken 01:59:57 INFO - operating with platform_type : linux_ 01:59:57 INFO - Initialising browser for kraken test... 01:59:57 INFO - TEST-INFO | started process 13202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyl1EOQ/profile http://localhost:35168/getInfo.html) 02:00:01 INFO - PROCESS | 13202 | __metrics Screen width/height:1600/1200 02:00:01 INFO - PROCESS | 13202 | colorDepth:24 02:00:01 INFO - PROCESS | 13202 | Browser inner width/height: 1024/697 02:00:01 INFO - PROCESS | 13202 | __metrics 02:00:02 INFO - PROCESS | 13202 | 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" 02:00:02 INFO - PROCESS | 13202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:00:03 INFO - TEST-INFO | 13202: exit 0 02:00:03 INFO - Browser initialized. 02:00:03 INFO - Running cycle 1/1 for kraken test... 02:00:03 INFO - TEST-INFO | started process 13286 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyl1EOQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:00:15 INFO - PROCESS | 13286 | RSS: Main: 157745152 02:00:15 INFO - PROCESS | 13286 | 02:00:17 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35168/tests/kraken/driver.html?testName=audio-beat-detection) 02:00:18 INFO - PROCESS | 13286 | RSS: Main: 216600576 02:00:18 INFO - PROCESS | 13286 | 02:00:21 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35168/tests/kraken/driver.html?testName=audio-dft) 02:00:21 INFO - PROCESS | 13286 | RSS: Main: 206204928 02:00:21 INFO - PROCESS | 13286 | 02:00:24 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35168/tests/kraken/driver.html?testName=audio-fft) 02:00:25 INFO - PROCESS | 13286 | RSS: Main: 269967360 02:00:25 INFO - PROCESS | 13286 | 02:00:27 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35168/tests/kraken/driver.html?testName=audio-oscillator) 02:00:28 INFO - PROCESS | 13286 | RSS: Main: 181223424 02:00:28 INFO - PROCESS | 13286 | 02:00:30 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35168/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:00:30 INFO - PROCESS | 13286 | RSS: Main: 184725504 02:00:30 INFO - PROCESS | 13286 | 02:00:33 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35168/tests/kraken/driver.html?testName=imaging-darkroom) 02:00:34 INFO - PROCESS | 13286 | RSS: Main: 219688960 02:00:34 INFO - PROCESS | 13286 | 02:00:37 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35168/tests/kraken/driver.html?testName=imaging-desaturate) 02:00:38 INFO - PROCESS | 13286 | RSS: Main: 221261824 02:00:38 INFO - PROCESS | 13286 | 02:00:41 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35168/tests/kraken/driver.html?testName=json-parse-financial) 02:00:41 INFO - PROCESS | 13286 | RSS: Main: 221982720 02:00:41 INFO - PROCESS | 13286 | 02:00:43 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35168/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:00:43 INFO - PROCESS | 13286 | RSS: Main: 209891328 02:00:43 INFO - PROCESS | 13286 | 02:00:45 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:00:46 INFO - PROCESS | 13286 | RSS: Main: 213630976 02:00:46 INFO - PROCESS | 13286 | 02:00:48 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:00:48 INFO - PROCESS | 13286 | RSS: Main: 212926464 02:00:48 INFO - PROCESS | 13286 | 02:00:51 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:00:52 INFO - PROCESS | 13286 | RSS: Main: 224718848 02:00:52 INFO - PROCESS | 13286 | 02:00:54 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:00:55 INFO - PROCESS | 13286 | RSS: Main: 226271232 02:00:55 INFO - PROCESS | 13286 | 02:00:56 INFO - PROCESS | 13286 | Cycle 1(1): loaded http://localhost:35168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35168/tests/kraken/driver.html?testName=ai-astar) 02:00:57 INFO - PROCESS | 13286 | RSS: Main: 268230656 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | __start_tp_report 02:00:57 INFO - PROCESS | 13286 | _x_x_mozilla_page_load 02:00:57 INFO - PROCESS | 13286 | _x_x_mozilla_page_load_details 02:00:57 INFO - PROCESS | 13286 | |i|pagename|runs| 02:00:57 INFO - PROCESS | 13286 | |0;ai-astar;102;111;111;109;111;110;110;111;110;111 02:00:57 INFO - PROCESS | 13286 | |1;audio-beat-detection;147;117;117;171;119;118;144;117;152;117 02:00:57 INFO - PROCESS | 13286 | |2;audio-dft;161;159;161;160;160;161;161;161;161;162 02:00:57 INFO - PROCESS | 13286 | |3;audio-fft;74;74;76;108;78;77;74;81;97;75 02:00:57 INFO - PROCESS | 13286 | |4;audio-oscillator;85;85;121;85;84;86;120;84;85;131 02:00:57 INFO - PROCESS | 13286 | |5;imaging-gaussian-blur;118;117;116;117;116;117;117;116;118;117 02:00:57 INFO - PROCESS | 13286 | |6;imaging-darkroom;145;146;145;146;145;146;146;146;146;146 02:00:57 INFO - PROCESS | 13286 | |7;imaging-desaturate;96;95;95;94;95;95;95;95;95;95 02:00:57 INFO - PROCESS | 13286 | |8;json-parse-financial;52;50;49;49;51;51;50;51;49;50 02:00:57 INFO - PROCESS | 13286 | |9;json-stringify-tinderbox;64;62;61;61;62;61;62;62;62;65 02:00:57 INFO - PROCESS | 13286 | |10;stanford-crypto-aes;84;74;75;75;74;74;77;75;74;75 02:00:57 INFO - PROCESS | 13286 | |11;stanford-crypto-ccm;118;125;117;118;131;133;123;119;193;140 02:00:57 INFO - PROCESS | 13286 | |12;stanford-crypto-pbkdf2;160;157;158;154;155;159;157;155;154;154 02:00:57 INFO - PROCESS | 13286 | |13;stanford-crypto-sha256-iterative;65;63;64;63;63;62;62;63;62;65 02:00:57 INFO - PROCESS | 13286 | __end_tp_report 02:00:57 INFO - PROCESS | 13286 | __start_cc_report 02:00:57 INFO - PROCESS | 13286 | _x_x_mozilla_cycle_collect,1640 02:00:57 INFO - PROCESS | 13286 | __end_cc_report 02:00:57 INFO - PROCESS | 13286 | __startTimestamp1468918857216__endTimestamp 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | ------- Summary: start ------- 02:00:57 INFO - PROCESS | 13286 | Number of tests: 14 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:2.76 (2.5%) stddev-sans-first:0.73 02:00:57 INFO - PROCESS | 13286 | Values: 102.0 111.0 111.0 109.0 111.0 110.0 110.0 111.0 110.0 111.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:131.50 stddev:19.87 (15.1%) stddev-sans-first:20.31 02:00:57 INFO - PROCESS | 13286 | Values: 147.0 117.0 117.0 171.0 119.0 118.0 144.0 117.0 152.0 117.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 02:00:57 INFO - PROCESS | 13286 | Values: 161.0 159.0 161.0 160.0 160.0 161.0 161.0 161.0 161.0 162.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.50 stddev:11.63 (15.2%) stddev-sans-first:12.02 02:00:57 INFO - PROCESS | 13286 | Values: 74.0 74.0 76.0 108.0 78.0 77.0 74.0 81.0 97.0 75.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:85.00 stddev:19.13 (22.5%) stddev-sans-first:19.83 02:00:57 INFO - PROCESS | 13286 | Values: 85.0 85.0 121.0 85.0 84.0 86.0 120.0 84.0 85.0 131.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 02:00:57 INFO - PROCESS | 13286 | Values: 118.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 118.0 117.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 02:00:57 INFO - PROCESS | 13286 | Values: 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 02:00:57 INFO - PROCESS | 13286 | Values: 96.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.50 stddev:1.03 (2.0%) stddev-sans-first:0.87 02:00:57 INFO - PROCESS | 13286 | Values: 52.0 50.0 49.0 49.0 51.0 51.0 50.0 51.0 49.0 50.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:1.32 (2.1%) stddev-sans-first:1.22 02:00:57 INFO - PROCESS | 13286 | Values: 64.0 62.0 61.0 61.0 62.0 61.0 62.0 62.0 62.0 65.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.00 stddev:3.06 (4.1%) stddev-sans-first:0.97 02:00:57 INFO - PROCESS | 13286 | Values: 84.0 74.0 75.0 75.0 74.0 74.0 77.0 75.0 74.0 75.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:128.00 stddev:22.86 (17.9%) stddev-sans-first:23.70 02:00:57 INFO - PROCESS | 13286 | Values: 118.0 125.0 117.0 118.0 131.0 133.0 123.0 119.0 193.0 140.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.30 Median:157.00 stddev:2.21 (1.4%) stddev-sans-first:1.90 02:00:57 INFO - PROCESS | 13286 | Values: 160.0 157.0 158.0 154.0 155.0 159.0 157.0 155.0 154.0 154.0 02:00:57 INFO - PROCESS | 13286 | 02:00:57 INFO - PROCESS | 13286 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.14 (1.8%) stddev-sans-first:1.00 02:00:57 INFO - PROCESS | 13286 | Values: 65.0 63.0 64.0 63.0 63.0 62.0 62.0 63.0 62.0 65.0 02:00:57 INFO - PROCESS | 13286 | -------- Summary: end -------- 02:00:57 INFO - PROCESS | 13286 | 02:00:58 INFO - TEST-INFO | 13286: exit 0 02:00:58 INFO - TEST-OK | kraken | took 60239ms 02:00:58 INFO - SUITE-END | took 1172s 02:00:58 INFO - Completed test suite (00:19:32) 02:00:58 INFO - javascript benchmark 02:00:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.9692103078964, 3099.550565168052, 3126.7498600111994, 3130.2487403023265, 3145.9685403145977, 5347.144456886899, 5398.13629819229, 5410.377806552247, 5536.474034966682, 5543.25166102576, 5410.269613602162, 5413.918791218135, 5462.0441422750955, 5501.284832971718, 5519.144532597444, 4857.902841943168, 4887.0940333270055, 4929.5316944890355, 4939.950600494003, 5061.519155680205, 4939.407271127469, 4978.5270399312185, 5003.749812509378, 5020.7991680332925, 5033.270175824503, 4853.247746599282, 4896.167651499253, 4897.461279259296, 4918.139325618038, 4930.6795058321095, 4887.0714564232685, 4917.901641967176, 4980.87547811304, 4981.900361992776, 5035.672681275705, 4852.175130227834, 4961.08219979305, 4961.231009193581, 4982.975085124587, 4995.5753760930365, 5326.7869285228935, 5404.864878378033, 5425.050616142159, 5474.589405794582, 5513.8345849624575, 5851.034579294412, 5855.238818953509, 5876.08920617305, 5907.379725128885, 5913.704314784243, 5640.8871822563315, 5903.734331955073, 5954.55340849438, 5955.761769529214, 5956.255468066492, 5450.482204190595, 5490.368607610136, 5513.889722205534, 5580.972095139498, 5586.664800112007, 6052.530836883904, 6061.2994041164075, 6067.893628015979, 6109.083637454418, 6121.846953826101, 5486.890262194734, 5671.716414179275, 5855.033919403336, 5855.707214639251, 5869.383714709979, 5804.332501762267, 5898.616589921641, 5911.940880591225, 5913.793017244376, 5916.408359164136, 5778.0, 5812.564057695689, 5825.33008703996, 5834.970825145847, 5855.414458554111, 5550.222968784374, 5763.106718458645, 5821.88356232873, 5858.56060795442, 5865.88268234633, 4439.42287502629, 4440.4227450431235, 4450.376947227391, 4478.283474644041, 4478.462584489882, 3970.0, 3985.0236692010644, 3990.3016972029777, 4017.859374921922, 4019.979900100481, 5386.434424385383, 5436.537894278951, 5529.474699903502, 5562.499375056264, 5593.244911936837, 5948.970255148696, 5966.463018328284, 5972.074328479092, 5983.042713165873, 6021.819345419644, 5615.6630602164005, 5624.97187514068, 5624.97187514068, 5625.746841392147, 5626.971865140648, 4838.661293709407, 5042.899142017139, 5089.618278629117, 5100.515451032146, 5107.540321371094, 6078.209832721718, 6103.3591472894705, 6107.480864126599, 6112.113743507255, 6112.908306375408, 5921.171036055042, 5954.255718035246, 5959.791407300897, 5963.4334738199805, 5964.612300200473, 5849.239598852292, 5856.472917437451, 5868.589198756046, 5892.5285977122585, 5974.970125149346, 5660.7169641518585, 5688.11834165705, 5690.886182276414, 5694.857628559319, 5697.173909782975, 5710.314762228559, 5742.856428589319, 5810.128480727924, 5812.982728022664, 5814.825555233349, 5682.062459694104, 5736.770529178786, 5738.0, 5760.0, 5769.74036168365, 5763.6541807491685, 5765.971170144123, 5770.163326317576, 5774.105013722879, 5774.422557744192, 5431.972840135774, 5597.888042239214, 5696.430356964436, 5706.971465142814, 5709.429057094423, 5752.1659359391815, 5805.013147764806, 5815.709214539341, 5856.677882716492, 5871.354151043467, 5651.886962260814, 5670.0, 5677.77288908439, 5685.175649530877, 5690.032694441872], "value": 5393.3053489411905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.1211243696638, 612.5099920063949, 628.0735914526075, 632.3960617610181, 651.3486513486514, 33093.17267068321, 35963.460548091796, 35975.640243597554, 43837.78081109598, 44944.32583511242, 46778.53221467793, 47107.52892471083, 47240.527594724044, 47353.28970065452, 47376.0, 8909.242714369286, 9020.77448063797, 9053.818923621524, 9058.0, 9062.320325975545, 8245.216704413071, 8426.957865210665, 8446.873296900552, 8659.870101948476, 9043.728688139334, 10885.945570272137, 11168.0, 11182.66452006437, 11487.770244595104, 11505.022073123795, 11026.724331891686, 11110.222284440093, 11113.666590002313, 11118.166137539676, 11127.33236005834, 8230.753077407688, 10481.842772358421, 10798.73003174919, 10887.965643263957, 10890.128789696806, 43950.560494395286, 44433.33349999753, 44454.333185001924, 44482.55517444816, 44527.77736111331, 3563.6436356364156, 3679.797611131387, 4015.8795236142964, 4287.742735435884, 4366.737995720236, 31478.527822082688, 33487.66512334894, 33702.66297337044, 34192.14519637004, 34270.828645856614, 3983.820728067244, 4014.658754005913, 4040.1313717550574, 4090.1001779608737, 4100.302948498733, 339427.0, 339458.0, 340560.0, 342011.0, 342603.0, 55622.443775561725, 55834.72082639642, 56026.879462411336, 56100.43899561034, 56187.43812561904, 64450.0, 64775.35224647787, 64778.0, 64787.352126479076, 64909.0, 17447.389341373244, 17893.821061789215, 17969.460916172535, 17972.9101354495, 17994.820051799314, 13794.723870561445, 13860.792088118686, 13865.168089914638, 13873.653158670912, 13879.86120138806, 17573.912130439523, 19412.902935485232, 19609.901950490155, 19682.70475942862, 19788.802111978985, 7289.271072892774, 7304.671289791972, 7446.367058800062, 7552.39580833532, 7703.1911649277135, 988.7429268390271, 1065.94137322448, 1071.749326414539, 1134.495149658394, 1173.1904985559936, 33685.32629347399, 34098.14754631154, 34249.97250082502, 34551.030823122404, 34654.653453465144, 1087.7280679830042, 1135.40391294571, 1139.0830381542771, 1151.0561339701364, 1203.1577895473204, 16982.32070717182, 17787.822121778874, 17789.859590493263, 17888.28446862111, 18012.729809052875, 594260.0, 917703.0, 929838.0, 930306.0, 930654.0, 61325.38674613286, 61647.0, 62083.379166208666, 62148.68925655343, 62187.37812621906, 1253.1729058821134, 1334.646318725519, 1388.6667199871958, 1406.1141480867193, 1412.69627030189, 32496.675033249365, 34343.48484772781, 34807.82596087003, 35049.0, 35065.824670877504, 50929.0, 51059.74470127626, 51475.742621286656, 51593.742031291105, 51723.44829655116, 451890.0, 455080.0, 455404.0, 457089.0, 458299.0, 24730.50538989246, 25479.61780573256, 26077.608835867857, 26168.0, 28765.28086797847, 43413.565864340955, 44902.32646510239, 48428.51571484381, 49408.25887611761, 49586.25620615621, 40605.0, 40845.59154408418, 41240.587594124874, 41345.586544135374, 42005.579944200166, 40248.798756006036, 40739.592604074765, 41371.793141034104, 41635.375469367376, 42072.579274206866, 40979.180416392104, 41438.1712365745, 42815.78592107019, 43675.12649747051, 43832.0, 36733.81633091818, 38585.0, 38965.80517097397, 39378.0, 39609.80195099006, 29272.853635731684, 29448.558271625516, 29551.0, 29699.703002969694, 30077.0], "value": 21171.195188129517, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.79129061597325, 401.38788347769645, 402.47477042459576, 405.2442195305755, 414.3508834837347, 18378.172982215798, 18884.905575472105, 19921.203151873906, 20064.097115629796, 20076.598468030632, 18921.337753178643, 19052.047397630096, 20304.69542956857, 20415.0, 20549.69175462369, 5507.807226747064, 5652.689102099373, 5801.767929282823, 5811.0, 5888.175655408247, 5732.570057245703, 5760.971195144019, 5773.047447171211, 5778.393268706788, 5781.39295373986, 6537.346265373472, 6550.443212326958, 6563.278039415661, 6568.1789776277965, 6570.211574611053, 5856.502197313233, 5864.76540938362, 6098.7560497579825, 6534.509911756637, 6536.934630653704, 5607.467290607386, 5616.32604087508, 5625.212470254127, 5634.802781902655, 5651.265335506399, 14162.575122746333, 20250.594988100303, 22820.261568611873, 23342.416439588975, 23921.16275930334, 2226.9109235630553, 2453.9754602453927, 2504.8998040078377, 2558.884850181728, 2915.8833646654107, 9384.624615015391, 10131.493425328705, 10665.733356666145, 10891.83662245067, 10932.234743567953, 1964.2143142742875, 1967.79338169493, 2016.062695362159, 2031.9898400507902, 2053.5460125150453, 84997.0, 87242.5637871813, 87816.0, 88839.0, 88974.55512722395, 10253.128484078938, 10694.572217111228, 10710.357378557312, 10742.248042637097, 11114.11087113029, 14590.270486475634, 14623.415063397344, 14626.0, 14649.194294313915, 14671.853281467262, 7967.521948683097, 8049.3962952778775, 8080.353571714366, 8084.0, 8169.183081691902, 7976.162502937224, 8009.4393392463135, 8017.559034253172, 8107.432479726481, 8127.309178719756, 4449.57729015743, 4503.909921801545, 4513.774311284423, 4533.818647254073, 4581.541845815392, 1819.954501137482, 1846.3260909767837, 1903.6763750162506, 1918.9712154317697, 1936.7772706138692, 983.7555492302243, 1028.4703377760459, 1036.9170466362823, 1046.5342922399611, 1081.1783044885785, 19222.327218547285, 20007.099680514413, 20331.18675253003, 20357.898210508854, 20438.59122817535, 980.0353929363732, 1009.3792317724693, 1012.7844094998351, 1055.3456856748712, 1067.0556557446716, 10498.370097794157, 11069.225154239288, 11117.332960022424, 11152.832707509393, 11167.944160279147, 506226.0, 508982.0, 526642.0, 528596.0, 529852.0, 93410.0, 95585.0, 96371.0, 98125.2639685962, 100951.49524252479, 954.398728800851, 968.2302569457157, 974.2011550528499, 983.2822039910905, 989.3074847606704, 17436.90757109155, 17470.825291747427, 17528.0, 17559.297628094733, 17893.821061789215, 44274.114517710106, 44982.325265121704, 45723.771381142884, 45833.0, 46200.07599848051, 56877.0, 56955.43044569667, 62971.685141574, 63768.3623163781, 64197.67901160464, 8057.798555036171, 8293.087760346478, 8350.874736879074, 8442.915570844212, 8489.872651910102, 12053.51785928553, 13114.344282785632, 13623.18260904349, 13918.304084795518, 14474.565763027125, 11715.707107322385, 12117.394130293274, 12137.332446715161, 12169.0, 12547.498100075894, 11408.0, 11543.653690389301, 11820.76358472843, 12058.216215945935, 12204.938975305067, 11368.261063030877, 11758.88241117612, 11989.1008174389, 12053.457594408097, 12345.197562158432, 8334.541600211804, 8681.744345870298, 8720.781980450285, 8885.111488851064, 8907.821843562962, 10724.464968469016, 11761.882381176421, 11781.293122412682, 12208.572699955459, 12310.446029928495], "value": 9400.758408078984, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.7504811609858, 1495.2374289112552, 1525.1611613612513, 1681.747737839324, 1814.9364772232975, 8119.188081191885, 8427.494350338984, 8436.072032076474, 8684.08817074206, 8688.348373871953, 8442.831143377143, 8606.784830379243, 8617.095205003478, 8624.741257762262, 8629.870551941725, 2050.5078781092507, 2289.2331069091874, 2336.2056900653733, 2361.3388251289666, 2368.786809187173, 2407.590709579376, 2410.337157281744, 2418.4074901649033, 2431.7932975697086, 2436.049940523197, 2311.6879221305076, 2368.218487898989, 2370.8221883358733, 2372.0748907925836, 2372.9525409491885, 1994.760628724555, 2192.3422973108004, 2273.943151421211, 2279.4073540879326, 2336.147306233221, 2311.2719493359537, 2331.3239160643393, 2332.2536788227776, 2332.661764044204, 2335.8364914455997, 9151.542422878829, 9205.769855753593, 9227.400218985744, 9261.073892610751, 9273.629054837798, 5364.463553644604, 5483.396826349099, 5497.945020549823, 5499.972500137474, 5501.119820828648, 3272.803631782077, 3306.718928891047, 3349.815760133192, 3365.125067482448, 3392.9830350848333, 936.3211671538148, 953.0564740906522, 956.7368973532265, 962.7978124593843, 964.1419136968104, 12280.0, 12582.370881455981, 12612.117151799377, 12932.418041188263, 12934.159279646792, 4511.255642818964, 4609.1703493371515, 4670.042641258534, 4691.577758001797, 4731.195696731564, 9961.203103751683, 9982.450965196984, 9992.25058120644, 9993.800123997626, 10000.099991000845, 5423.2136340230445, 5425.864353391118, 5428.864278393072, 5431.538319242909, 5449.128139497661, 4440.156370289635, 4892.119418504703, 5012.24816277554, 5025.246213068069, 5090.41460232078, 3764.153065560282, 3832.2143960488033, 3879.6702280305926, 3946.723729338919, 3953.110550126256, 1042.468341146021, 1093.6609651008214, 1117.0, 1123.2474242257706, 1136.880627534097, 929.693201243588, 1007.315025782475, 1022.8568000479938, 1024.938503689781, 1025.7179275699243, 6128.356522565066, 6137.662428566472, 6145.231846019247, 6254.093156492374, 6322.810315690536, 986.6152200641756, 988.5172241637543, 1003.5835128421769, 1017.877854657431, 1026.8716410448694, 4267.295896177158, 4396.538363471854, 4418.668599855024, 4450.977745111254, 4455.68810183284, 10878.782424351468, 11302.773944521063, 11318.490667919963, 11335.773284534429, 11348.716282093013, 10661.520231589597, 11121.165912556591, 11125.72185695348, 11262.380569068617, 11458.77082458363, 8337.374696897636, 8447.493150410995, 8451.197136272045, 8462.407631465738, 8468.40721149526, 6249.875002500015, 6265.843353916189, 6435.646039467874, 6441.935580644321, 6527.314631963765, 10059.19526437898, 10689.0, 10693.465326733787, 10709.303895247096, 10808.243422960632, 10542.156627469938, 10766.407847568145, 10780.29928054674, 10797.406142661916, 10877.782444351227, 7985.800354991172, 8053.476524025919, 8076.757697268857, 8094.433389662667, 8185.8772118419465, 8858.202761751216, 8864.0, 8881.911180888366, 8929.553522323988, 8932.687355942771, 7986.7603971880935, 8114.310283625958, 8192.795180120545, 8199.098099208963, 8209.179082091743, 7843.960780196063, 7939.801504962191, 7968.721094761656, 8052.074011488754, 8114.634841432267, 6874.725010999504, 6920.757773478105, 6946.722131114901, 6971.65141742921, 6980.511364204457, 5291.365036195682, 5379.919301210563, 5519.420460851553, 5556.305461817273, 5615.073512870331, 5403.702796346081, 5533.2253484513, 5605.299337582802, 5677.77288908439, 5694.4590263924865], "value": 4712.146873991825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.9344131173766, 330.36404920527985, 337.2698108594892, 337.3556507071491, 337.989860304191, 5203.167493201088, 5302.867428314285, 5468.316460442445, 5519.779208831647, 5530.4746049125315, 5293.206019097137, 5424.674519528831, 5436.103042997901, 5439.048166570854, 5473.343198816147, 4251.256030194719, 4258.318669012959, 4261.254280500915, 4265.616094551489, 4282.079352939118, 4017.7589344639414, 4017.959820401788, 4211.536730959592, 4279.443672322594, 4282.97858510707, 4196.685248606352, 4202.915941681164, 4208.663306935453, 4209.85265515707, 4213.683973701969, 4188.1204946961025, 4189.455370801791, 4198.769067701275, 4201.432806571104, 4217.683673724483, 3606.4049431843714, 3687.3547129252274, 3700.833462494167, 3702.3520883845217, 3715.7584756990977, 5216.921746173811, 5223.399309079428, 5260.395054568697, 5271.103912334874, 5278.392984806719, 4760.381150450454, 4780.904381912377, 4793.808247670089, 4819.807207711688, 4827.758612069382, 5387.865303367408, 5567.0, 5612.5126841386655, 5626.915596266018, 5636.2954630671165, 3940.349842276021, 3944.7041471889725, 4006.2988976929214, 4009.3985902114623, 4043.413705012786, 5759.625624334447, 5767.538596912237, 5785.644326290741, 5804.477597016289, 5804.810013947131, 3941.625545573166, 4104.938425923614, 4120.979395103065, 4123.60825721556, 4129.483814523184, 4184.91630167401, 4191.077962848169, 4194.748315101104, 4200.285951388271, 4201.327787553977, 4855.6601037927, 4858.61529464101, 4865.245886887538, 4868.975655121772, 4874.073925954128, 5536.8062117826485, 5692.231548740867, 5729.541636669056, 5766.163906233573, 5778.8266352009505, 1430.8926830487756, 1436.77011678131, 1445.3640398224748, 1445.9566213013627, 1450.100937418807, 903.421810041574, 929.3215952354684, 940.2478017585904, 940.2853831088422, 945.5603144537686, 4877.780499877514, 4877.804937790193, 4888.706677599355, 4921.606271498272, 4935.457099719028, 5120.308758317654, 5132.204508301219, 5136.306598609214, 5197.714125723046, 5205.453427390145, 4712.0, 4828.66199366048, 4830.734309613005, 4915.132249745074, 4991.775370108354, 2781.902633407821, 2800.103966730627, 2805.368792021779, 2820.5615136280235, 2839.799155654343, 5239.266502689627, 5341.866453338697, 5360.222767698662, 5418.8916221675345, 5419.295491586068, 5266.815661451831, 5337.224643185522, 5570.498655121059, 6031.517478601701, 6038.698065096728, 6018.759249629966, 6024.397560244028, 6041.003234466351, 6050.6369617823075, 6066.178307666142, 5624.581429985145, 5911.852203694942, 5916.437938396018, 5937.673427961504, 5944.494717949023, 5773.653580785166, 5779.595428319977, 5785.595008349375, 5805.477507024219, 5819.5053420459735, 5989.401059893976, 5991.101334799815, 5997.760089596367, 6056.636601803906, 6075.544334174866, 3853.8843834685003, 3941.467901833216, 4069.104796944667, 4080.4491393661474, 4090.6318431341324, 5819.621724587888, 5828.737706803119, 5844.211031510862, 5846.35690074083, 5894.941050589439, 5605.1872478491205, 5652.152177173457, 5669.631473954345, 5749.786332262026, 5766.654000759968, 5615.522680572034, 5626.803061892814, 5630.971845140748, 5634.211210430625, 5681.971590142188, 5553.888922221614, 5566.582506311962, 5624.803131890528, 5638.0, 5642.210090587403, 5606.271184745957, 5649.265595472562, 5681.119426488901, 5692.743826527733, 5762.596618236683, 5531.917021244604, 5569.989607482606, 5575.470330318613, 5597.748101335368, 5616.9327827712605], "value": 4245.183479019313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.3682979956435, 787.2678409079557, 810.5947026486757, 818.4148333941233, 851.0468275531406, 15756.84243157568, 16860.325586976505, 16881.071541065252, 16883.83116168838, 16909.323627054917, 14549.127052376838, 14615.12309261445, 14633.122012679247, 14635.926820365883, 14638.926805365985, 664.5547483186277, 809.6356639512213, 836.0385556609917, 839.1985653700732, 843.3590471241839, 830.730012745857, 838.9916100839005, 839.5340585974782, 841.6549214821924, 841.9957900210491, 697.4350775871535, 884.907084756101, 886.5965985476593, 886.6719313853883, 888.0675290944482, 851.8849955256054, 869.8303830753006, 870.9695160669346, 879.6261588824788, 880.9559522023873, 638.7636574467476, 646.1954866191575, 646.444058110025, 646.612032780328, 647.0100745858844, 15479.14864682442, 15502.0, 15533.14567698776, 15537.0, 15541.689166216725, 14249.928750356183, 14753.409863605442, 14790.70418591633, 14807.111573305529, 14875.330610122463, 11334.943325283402, 11657.883421165849, 12079.698007549794, 13368.86631133686, 13681.931590342085, 4296.74855455347, 4345.348197770328, 4369.038811461473, 4407.162639098593, 4430.246858034142, 62718.68640656768, 62997.370026299606, 71036.0, 74473.62763186204, 75116.24883751148, 11773.17587768865, 11796.58711945095, 11842.052635789116, 11994.280343179436, 12002.819957700642, 20456.795432045787, 20594.24948879332, 20594.27920022822, 20606.484837879172, 20668.793312066988, 8631.525266110299, 8634.093420190915, 8638.049814520398, 8643.827123457495, 8731.170538798804, 4852.126617208936, 4887.340209071801, 4953.0589188054155, 4967.776450059756, 5059.165237735732, 3435.811030393352, 3512.8594856205978, 3524.400851855191, 3532.293541291752, 3543.6279190685123, 631.1321932343028, 642.8842808294552, 650.9446697030805, 658.3383699382186, 659.0476761080289, 588.7880363069291, 637.4914710613657, 639.389383139101, 671.8992151177265, 688.0745397440435, 9190.356675032683, 9530.857037144448, 9579.90420095804, 9834.803303934024, 10251.84622230667, 682.2256738601723, 696.9581825090511, 707.9115110611174, 718.2422544215798, 734.2143906020614, 4288.163808057461, 4451.311298680041, 4542.909141817211, 4572.108438854458, 4577.679562430598, 66038.33961660322, 81598.0, 113901.0, 115442.42278788552, 115544.42227788975, 99003.50498247462, 99010.0, 100493.0, 100708.49645751725, 101318.0, 66651.0, 86439.1356086431, 87825.56087219651, 88521.55739221392, 89053.0, 8489.320854331761, 8529.616167272612, 8668.34987375962, 8772.692955746517, 9001.684941027295, 52710.73644631906, 52749.20876186937, 52776.736116319174, 53834.730826345614, 53942.0, 66614.0, 67315.0, 71178.0, 71727.28272717414, 73904.63047684728, 10534.628680414902, 10568.89431105679, 10759.3544387337, 10811.567537298735, 10969.50637221311, 12675.30285834288, 12724.618261452171, 12944.093913426346, 13032.869671303166, 13049.804252936403, 12144.939275303921, 12210.63368098923, 12250.0, 12504.874951250735, 13215.60353189406, 12497.500099995897, 12633.747325053631, 12655.746885062432, 12915.289659068842, 13316.067875249028, 12184.939075304566, 12369.93815030919, 12499.500019999461, 12842.101052926206, 13010.024248181235, 10183.0, 10328.431936243578, 10724.410157441416, 10735.248532602644, 10977.28647637901, 10177.185825133824, 10643.574257029632, 10644.0, 10672.57309707603, 10701.518431670438], "value": 7417.529395316911, "unit": "score"}], "name": "dromaeo_css", "value": 7362.591296955625, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 109.0, 111.0, 110.0, 110.0, 111.0, 110.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 117.0, 171.0, 119.0, 118.0, 144.0, 117.0, 152.0, 117.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 108.0, 78.0, 77.0, 74.0, 81.0, 97.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 121.0, 85.0, 84.0, 86.0, 120.0, 84.0, 85.0, 131.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 94.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": [52.0, 50.0, 49.0, 49.0, 51.0, 51.0, 50.0, 51.0, 49.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 61.0, 62.0, 61.0, 62.0, 62.0, 62.0, 65.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 74.0, 75.0, 75.0, 74.0, 74.0, 77.0, 75.0, 74.0, 75.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 125.0, 117.0, 118.0, 131.0, 133.0, 123.0, 119.0, 193.0, 140.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 157.0, 158.0, 154.0, 155.0, 159.0, 157.0, 155.0, 154.0, 154.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 65.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1477.1000000000001, "alertThreshold": 2.0}]} 02:00:58 INFO - Return code: 0 02:00:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:00:58 INFO - # TBPL SUCCESS # 02:00:58 INFO - Running post-action listener: _resource_record_post_action 02:00:58 INFO - ##### 02:00:58 INFO - ##### Finished run-tests step (success) 02:00:58 INFO - ##### 02:00:58 INFO - Running post-run listener: _resource_record_post_run 02:00:58 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 563064832; Read time: 32; Write time: 445224 02:00:58 INFO - TinderboxPrint: CPU usage
13.0% 02:00:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 32 02:00:58 INFO - TinderboxPrint: I/O write bytes / time
563,064,832 / 445,224 02:00:58 INFO - TinderboxPrint: CPU idle
8,264.8 (87.0%) 02:00:58 INFO - TinderboxPrint: CPU user
1,191.0 (12.5%) 02:00:58 INFO - TinderboxPrint: Swap in / out
0 / 0 02:00:58 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 266866688; Read time: 0; Write time: 255228 02:00:58 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 296198144; Read time: 0; Write time: 189996 02:00:59 INFO - Running post-run listener: _upload_blobber_files 02:00:59 INFO - Blob upload gear active. 02:00:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:00:59 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 02:00:59 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:00:59 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:00:59 INFO - (blobuploader) - INFO - Open directory for files ... 02:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:01:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:04 INFO - (blobuploader) - INFO - Done attempting. 02:01:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:01:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:01:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:05 INFO - (blobuploader) - INFO - Done attempting. 02:01:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:01:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:01:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:06 INFO - (blobuploader) - INFO - Done attempting. 02:01:06 INFO - (blobuploader) - INFO - Iteration through files over. 02:01:06 INFO - Return code: 0 02:01:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:01:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:01:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e364eebb1b301c153ea1a1e6e45a8fa82b372d85430f6a0c74f9f17fd38443f25a86ba8ccf7f39e99d323cc44f60fffce62b7911acb76f739a1c13463948c2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0d2ef7fe059387bf2a988f5be97972c7903c8f20dfd228d9373562d8152a893a446e9643dffd59167bc432426a9de07d176e303602aca5a0d7ab81efe7854c8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de84ecbfeb692a66334a5d2af0b9acd337969c2907ae844e97aca7709bc8903ac152667c92c0f99e09c1e35f81edd6b02d96104e07bcd43e64429764039a4fb3"} 02:01:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:01:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:01:06 INFO - Contents: 02:01:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e364eebb1b301c153ea1a1e6e45a8fa82b372d85430f6a0c74f9f17fd38443f25a86ba8ccf7f39e99d323cc44f60fffce62b7911acb76f739a1c13463948c2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0d2ef7fe059387bf2a988f5be97972c7903c8f20dfd228d9373562d8152a893a446e9643dffd59167bc432426a9de07d176e303602aca5a0d7ab81efe7854c8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de84ecbfeb692a66334a5d2af0b9acd337969c2907ae844e97aca7709bc8903ac152667c92c0f99e09c1e35f81edd6b02d96104e07bcd43e64429764039a4fb3"} 02:01:06 INFO - Running post-run listener: copy_logs_to_upload_dir 02:01:06 INFO - Copying logs to upload dir... 02:01:06 INFO - mkdir: /builds/slave/test/build/upload/logs 02:01:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.029275 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-19 02:01:05.982996) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 02:01:05.986758) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e364eebb1b301c153ea1a1e6e45a8fa82b372d85430f6a0c74f9f17fd38443f25a86ba8ccf7f39e99d323cc44f60fffce62b7911acb76f739a1c13463948c2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0d2ef7fe059387bf2a988f5be97972c7903c8f20dfd228d9373562d8152a893a446e9643dffd59167bc432426a9de07d176e303602aca5a0d7ab81efe7854c8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de84ecbfeb692a66334a5d2af0b9acd337969c2907ae844e97aca7709bc8903ac152667c92c0f99e09c1e35f81edd6b02d96104e07bcd43e64429764039a4fb3"} build_url:https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/KcFsTzOKTIy1MTBqeyutOQ/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/Mozilla-Inbound/sha512/4e364eebb1b301c153ea1a1e6e45a8fa82b372d85430f6a0c74f9f17fd38443f25a86ba8ccf7f39e99d323cc44f60fffce62b7911acb76f739a1c13463948c2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0d2ef7fe059387bf2a988f5be97972c7903c8f20dfd228d9373562d8152a893a446e9643dffd59167bc432426a9de07d176e303602aca5a0d7ab81efe7854c8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de84ecbfeb692a66334a5d2af0b9acd337969c2907ae844e97aca7709bc8903ac152667c92c0f99e09c1e35f81edd6b02d96104e07bcd43e64429764039a4fb3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 02:01:06.011622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:01:06.011917) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468917634.690028-685037933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:01:06.094034) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 02:01:06.094354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 02:01:06.094629) ========= ========= Total master_lag: 0.15 =========