builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1468289702.1 results: success (0) buildid: 20160711163014 builduid: 7e168951d4584ac1bec316dc30faf097 revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.103909) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.104331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.104631) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.149771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.150072) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 19:15:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-07-11 19:15:02 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094379 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.269996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.270288) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017473 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:02.334453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 19:15:02.334849) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 19:15:02,711 truncating revision to first 12 chars 2016-07-11 19:15:02,711 Setting DEBUG logging. 2016-07-11 19:15:02,711 attempt 1/10 2016-07-11 19:15:02,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/88bebcaca249?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 19:15:03,341 unpacking tar archive at: mozilla-central-88bebcaca249/testing/mozharness/ program finished with exit code 0 elapsedTime=1.030477 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 19:15:03.384160) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:03.384685) ========= script_repo_revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:03.385273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:03.385743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 19:15:03.404321) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-11 19:15:03.404826) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:15:03 INFO - MultiFileLogger online at 20160711 19:15:03 in /builds/slave/test 19:15:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:15:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:15:03 INFO - {'append_to_log': False, 19:15:03 INFO - 'base_work_dir': '/builds/slave/test', 19:15:03 INFO - 'blob_upload_branch': 'Firefox', 19:15:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:15:03 INFO - 'branch': 'Firefox', 19:15:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:15:03 INFO - 'config_files': ('talos/linux_config.py',), 19:15:03 INFO - 'default_actions': ('clobber', 19:15:03 INFO - 'read-buildbot-config', 19:15:03 INFO - 'download-and-extract', 19:15:03 INFO - 'populate-webroot', 19:15:03 INFO - 'create-virtualenv', 19:15:03 INFO - 'install', 19:15:03 INFO - 'run-tests'), 19:15:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:15:03 INFO - 'download_minidump_stackwalk': True, 19:15:03 INFO - 'download_symbols': 'ondemand', 19:15:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:15:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:15:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:15:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:15:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:15:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:15:03 INFO - 'installer_path': 'installer.exe', 19:15:03 INFO - 'log_level': 'info', 19:15:03 INFO - 'log_name': 'talos', 19:15:03 INFO - 'log_to_console': True, 19:15:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:15:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:15:03 INFO - 'opt_config_files': (), 19:15:03 INFO - 'pip_index': False, 19:15:03 INFO - 'sps_profile': False, 19:15:03 INFO - 'sps_profile_interval': 0, 19:15:03 INFO - 'suite': 'dromaeojs', 19:15:03 INFO - 'system_bits': '32', 19:15:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:15:03 INFO - 'title': 'talos-linux64-ix-013', 19:15:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:15:03 INFO - 'use_talos_json': True, 19:15:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:15:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:15:03 INFO - 'work_dir': 'build'} 19:15:03 INFO - ##### 19:15:03 INFO - ##### Running clobber step. 19:15:03 INFO - ##### 19:15:03 INFO - Running pre-action listener: _resource_record_pre_action 19:15:03 INFO - Running main action method: clobber 19:15:03 INFO - rmtree: /builds/slave/test/build 19:15:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:15:04 INFO - Running post-action listener: _resource_record_post_action 19:15:04 INFO - ##### 19:15:04 INFO - ##### Finished clobber step (success) 19:15:04 INFO - ##### 19:15:04 INFO - ##### 19:15:04 INFO - ##### Running read-buildbot-config step. 19:15:04 INFO - ##### 19:15:04 INFO - Running pre-action listener: _resource_record_pre_action 19:15:04 INFO - Running main action method: read_buildbot_config 19:15:04 INFO - Using buildbot properties: 19:15:04 INFO - { 19:15:04 INFO - "project": "", 19:15:04 INFO - "product": "firefox", 19:15:04 INFO - "script_repo_revision": "production", 19:15:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:15:04 INFO - "repository": "", 19:15:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:15:04 INFO - "buildid": "20160711163014", 19:15:04 INFO - "pgo_build": "True", 19:15:04 INFO - "basedir": "/builds/slave/test", 19:15:04 INFO - "buildnumber": 381, 19:15:04 INFO - "slavename": "talos-linux64-ix-013", 19:15:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:15:04 INFO - "platform": "ubuntu64_hw", 19:15:04 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:15:04 INFO - "branch": "mozilla-central", 19:15:04 INFO - "repo_path": "mozilla-central", 19:15:04 INFO - "revision": "88bebcaca249aeaca9197382e89d35b02be8292e", 19:15:04 INFO - "stage_platform": "linux64-pgo", 19:15:04 INFO - "builduid": "7e168951d4584ac1bec316dc30faf097", 19:15:04 INFO - "slavebuilddir": "test" 19:15:04 INFO - } 19:15:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 19:15:04 INFO - Running post-action listener: _resource_record_post_action 19:15:04 INFO - ##### 19:15:04 INFO - ##### Finished read-buildbot-config step (success) 19:15:04 INFO - ##### 19:15:04 INFO - ##### 19:15:04 INFO - ##### Running download-and-extract step. 19:15:04 INFO - ##### 19:15:04 INFO - Running pre-action listener: _resource_record_pre_action 19:15:04 INFO - Running main action method: download_and_extract 19:15:04 INFO - mkdir: /builds/slave/test/build/tests 19:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:04 INFO - https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:15:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:15:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:15:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:07 INFO - Downloaded 1361 bytes. 19:15:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:15:07 INFO - Using the following test package requirements: 19:15:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:15:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:15:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:15:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'jsshell-linux-x86_64.zip'], 19:15:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:15:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:15:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:15:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:15:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:15:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:15:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:15:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:15: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')]} 19:15:07 INFO - https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:15:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:15:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:15:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:09 INFO - Downloaded 24270059 bytes. 19:15:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:15:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:15:10 INFO - Return code: 0 19:15:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:10 INFO - https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:15:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:15:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:15:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:13 INFO - Downloaded 24270059 bytes. 19:15:13 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'] 19:15:13 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 19:15:15 INFO - Return code: 0 19:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:15 INFO - https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:15:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:15:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:15:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:16 INFO - Downloaded 14403333 bytes. 19:15:16 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'] 19:15:16 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 19:15:16 INFO - Return code: 0 19:15:16 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')]} 19:15:16 INFO - https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:15:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:15:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:15:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/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 19:15:18 INFO - Downloaded 60491484 bytes. 19:15:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:15:18 INFO - mkdir: /builds/slave/test/properties 19:15:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:15:18 INFO - Writing to file /builds/slave/test/properties/build_url 19:15:18 INFO - Contents: 19:15:18 INFO - build_url:https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:15:19 INFO - Running post-action listener: _resource_record_post_action 19:15:19 INFO - Running post-action listener: set_extra_try_arguments 19:15:19 INFO - ##### 19:15:19 INFO - ##### Finished download-and-extract step (success) 19:15:19 INFO - ##### 19:15:19 INFO - ##### 19:15:19 INFO - ##### Running populate-webroot step. 19:15:19 INFO - ##### 19:15:19 INFO - Running pre-action listener: _resource_record_pre_action 19:15:19 INFO - Running main action method: populate_webroot 19:15:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:15:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:15:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:15:19 INFO - '--tppagecycles', 19:15:19 INFO - '7'], 19:15:19 INFO - 'tests': ['tsvgm']}}, 19:15:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:15:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:15:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:15:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:15:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['damp', 'tps']}, 19:15:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'tests': ['damp', 'tps']}, 19:15:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['dromaeo_dom']}, 19:15:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:15:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['basic_compositor_video']}, 19:15:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:15:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'other-e10s': {'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['a11yr', 19:15:19 INFO - 'ts_paint', 19:15:19 INFO - 'tpaint', 19:15:19 INFO - 'sessionrestore', 19:15:19 INFO - 'sessionrestore_no_auto_restore', 19:15:19 INFO - 'tabpaint']}, 19:15:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['tsvgx', 19:15:19 INFO - 'tsvgr_opacity', 19:15:19 INFO - 'tart', 19:15:19 INFO - 'tscrollx', 19:15:19 INFO - 'cart']}, 19:15:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:15:19 INFO - 'tsvgr_opacity', 19:15:19 INFO - 'tart', 19:15:19 INFO - 'tscrollx', 19:15:19 INFO - 'cart']}, 19:15:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'talos_options': ['--disable-e10s'], 19:15:19 INFO - 'tests': ['tp5o']}, 19:15:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'tests': ['tp5o']}, 19:15:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'talos_options': ['--disable-e10s', 19:15:19 INFO - '--xperf_path', 19:15:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:19 INFO - 'tests': ['tp5n']}, 19:15:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:19 INFO - 'talos_options': ['--xperf_path', 19:15:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:19 INFO - 'tests': ['tp5n']}}, 19:15:19 INFO - 'talos.zip': {'path': '', 19:15:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:15:19 INFO - Running post-action listener: _resource_record_post_action 19:15:19 INFO - ##### 19:15:19 INFO - ##### Finished populate-webroot step (success) 19:15:19 INFO - ##### 19:15:19 INFO - ##### 19:15:19 INFO - ##### Running create-virtualenv step. 19:15:19 INFO - ##### 19:15:19 INFO - Running pre-action listener: _resource_record_pre_action 19:15:19 INFO - Running main action method: create_virtualenv 19:15:19 INFO - Creating virtualenv /builds/slave/test/build/venv 19:15:19 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 19:15:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:15:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:15:19 INFO - Using real prefix '/usr' 19:15:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:15:19 INFO - Installing distribute.............................................................................................................................................................................................done. 19:15:21 INFO - Installing pip.................done. 19:15:21 INFO - Return code: 0 19:15:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:15:21 INFO - Using env: {'DISPLAY': ':0', 19:15:21 INFO - 'HOME': '/home/cltbld', 19:15:21 INFO - 'LANG': 'en_US.UTF-8', 19:15:21 INFO - 'LANGUAGE': 'en_US:en', 19:15:21 INFO - 'LOGNAME': 'cltbld', 19:15:21 INFO - 'MAIL': '/var/mail/cltbld', 19:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:21 INFO - 'MOZ_NO_REMOTE': '1', 19:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:21 INFO - 'NO_EM_RESTART': '1', 19:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:21 INFO - 'PWD': '/builds/slave/test', 19:15:21 INFO - 'SHELL': '/bin/bash', 19:15:21 INFO - 'SHLVL': '1', 19:15:21 INFO - 'TERM': 'linux', 19:15:21 INFO - 'TMOUT': '86400', 19:15:21 INFO - 'USER': 'cltbld', 19:15:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:21 INFO - '_': '/tools/buildbot/bin/python'} 19:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:21 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 19:15:21 INFO - Cleaning up... 19:15:21 INFO - Return code: 0 19:15:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:15:21 INFO - Using env: {'DISPLAY': ':0', 19:15:21 INFO - 'HOME': '/home/cltbld', 19:15:21 INFO - 'LANG': 'en_US.UTF-8', 19:15:21 INFO - 'LANGUAGE': 'en_US:en', 19:15:21 INFO - 'LOGNAME': 'cltbld', 19:15:21 INFO - 'MAIL': '/var/mail/cltbld', 19:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:21 INFO - 'MOZ_NO_REMOTE': '1', 19:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:21 INFO - 'NO_EM_RESTART': '1', 19:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:21 INFO - 'PWD': '/builds/slave/test', 19:15:21 INFO - 'SHELL': '/bin/bash', 19:15:21 INFO - 'SHLVL': '1', 19:15:21 INFO - 'TERM': 'linux', 19:15:21 INFO - 'TMOUT': '86400', 19:15:21 INFO - 'USER': 'cltbld', 19:15:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:21 INFO - '_': '/tools/buildbot/bin/python'} 19:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:22 INFO - Downloading/unpacking psutil>=3.1.1 19:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:15:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:24 INFO - Installing collected packages: psutil 19:15:24 INFO - Running setup.py install for psutil 19:15:24 INFO - building 'psutil._psutil_linux' extension 19:15:24 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 19:15:24 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 19:15:24 INFO - building 'psutil._psutil_posix' extension 19:15:24 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 19:15:24 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 19:15:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:24 INFO - Successfully installed psutil 19:15:24 INFO - Cleaning up... 19:15:24 INFO - Return code: 0 19:15:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:15:24 INFO - Using env: {'DISPLAY': ':0', 19:15:24 INFO - 'HOME': '/home/cltbld', 19:15:24 INFO - 'LANG': 'en_US.UTF-8', 19:15:24 INFO - 'LANGUAGE': 'en_US:en', 19:15:24 INFO - 'LOGNAME': 'cltbld', 19:15:24 INFO - 'MAIL': '/var/mail/cltbld', 19:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:24 INFO - 'MOZ_NO_REMOTE': '1', 19:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:24 INFO - 'NO_EM_RESTART': '1', 19:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:24 INFO - 'PWD': '/builds/slave/test', 19:15:24 INFO - 'SHELL': '/bin/bash', 19:15:24 INFO - 'SHLVL': '1', 19:15:24 INFO - 'TERM': 'linux', 19:15:24 INFO - 'TMOUT': '86400', 19:15:24 INFO - 'USER': 'cltbld', 19:15:24 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:24 INFO - '_': '/tools/buildbot/bin/python'} 19:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:15:26 INFO - Installing collected packages: mozsystemmonitor 19:15:26 INFO - Running setup.py install for mozsystemmonitor 19:15:26 INFO - Successfully installed mozsystemmonitor 19:15:26 INFO - Cleaning up... 19:15:27 INFO - Return code: 0 19:15:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:15:27 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')]} 19:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:27 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')]} 19:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:27 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:27 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 19:15:27 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 19:15:27 INFO - Using env: {'DISPLAY': ':0', 19:15:27 INFO - 'HOME': '/home/cltbld', 19:15:27 INFO - 'LANG': 'en_US.UTF-8', 19:15:27 INFO - 'LANGUAGE': 'en_US:en', 19:15:27 INFO - 'LOGNAME': 'cltbld', 19:15:27 INFO - 'MAIL': '/var/mail/cltbld', 19:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:27 INFO - 'MOZ_NO_REMOTE': '1', 19:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:27 INFO - 'NO_EM_RESTART': '1', 19:15:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:27 INFO - 'PWD': '/builds/slave/test', 19:15:27 INFO - 'SHELL': '/bin/bash', 19:15:27 INFO - 'SHLVL': '1', 19:15:27 INFO - 'TERM': 'linux', 19:15:27 INFO - 'TMOUT': '86400', 19:15:27 INFO - 'USER': 'cltbld', 19:15:27 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:27 INFO - '_': '/tools/buildbot/bin/python'} 19:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:27 INFO - Downloading/unpacking jsonschema==2.5.1 19:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:29 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:29 INFO - warning: no files found matching '*.txt' 19:15:29 INFO - no previously-included directories found matching 'build' 19:15:29 INFO - no previously-included directories found matching 'dist' 19:15:29 INFO - no previously-included directories found matching '.git*' 19:15:29 INFO - Installing collected packages: jsonschema, functools32 19:15:29 INFO - Running setup.py install for functools32 19:15:29 INFO - warning: no files found matching '*.txt' 19:15:29 INFO - no previously-included directories found matching 'build' 19:15:29 INFO - no previously-included directories found matching 'dist' 19:15:29 INFO - no previously-included directories found matching '.git*' 19:15:29 INFO - Successfully installed jsonschema functools32 19:15:29 INFO - Cleaning up... 19:15:29 INFO - Return code: 0 19:15:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:15:29 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')]} 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:29 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')]} 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:29 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:29 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 19:15:29 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 19:15:29 INFO - Using env: {'DISPLAY': ':0', 19:15:29 INFO - 'HOME': '/home/cltbld', 19:15:29 INFO - 'LANG': 'en_US.UTF-8', 19:15:29 INFO - 'LANGUAGE': 'en_US:en', 19:15:29 INFO - 'LOGNAME': 'cltbld', 19:15:29 INFO - 'MAIL': '/var/mail/cltbld', 19:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:29 INFO - 'MOZ_NO_REMOTE': '1', 19:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:29 INFO - 'NO_EM_RESTART': '1', 19:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:29 INFO - 'PWD': '/builds/slave/test', 19:15:29 INFO - 'SHELL': '/bin/bash', 19:15:29 INFO - 'SHLVL': '1', 19:15:29 INFO - 'TERM': 'linux', 19:15:29 INFO - 'TMOUT': '86400', 19:15:29 INFO - 'USER': 'cltbld', 19:15:29 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:29 INFO - '_': '/tools/buildbot/bin/python'} 19:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:29 INFO - Downloading/unpacking blobuploader==1.2.4 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:31 INFO - Downloading blobuploader-1.2.4.tar.gz 19:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:15:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:15:31 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 19:15:31 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 19:15:31 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 19:15:31 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 19:15:31 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 19:15:31 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 19:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:15:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:15:32 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 19:15:32 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 19:15:32 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 19:15:32 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 19:15:32 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 19:15:32 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 19:15:32 INFO - Downloading docopt-0.6.1.tar.gz 19:15:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:15:32 INFO - Installing collected packages: blobuploader, requests, docopt 19:15:32 INFO - Running setup.py install for blobuploader 19:15:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:15:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:15:32 INFO - Running setup.py install for requests 19:15:32 INFO - Running setup.py install for docopt 19:15:32 INFO - Successfully installed blobuploader requests docopt 19:15:32 INFO - Cleaning up... 19:15:33 INFO - Return code: 0 19:15:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:33 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:33 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 19:15:33 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 19:15:33 INFO - Using env: {'DISPLAY': ':0', 19:15:33 INFO - 'HOME': '/home/cltbld', 19:15:33 INFO - 'LANG': 'en_US.UTF-8', 19:15:33 INFO - 'LANGUAGE': 'en_US:en', 19:15:33 INFO - 'LOGNAME': 'cltbld', 19:15:33 INFO - 'MAIL': '/var/mail/cltbld', 19:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:33 INFO - 'MOZ_NO_REMOTE': '1', 19:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:33 INFO - 'NO_EM_RESTART': '1', 19:15:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:33 INFO - 'PWD': '/builds/slave/test', 19:15:33 INFO - 'SHELL': '/bin/bash', 19:15:33 INFO - 'SHLVL': '1', 19:15:33 INFO - 'TERM': 'linux', 19:15:33 INFO - 'TMOUT': '86400', 19:15:33 INFO - 'USER': 'cltbld', 19:15:33 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:33 INFO - '_': '/tools/buildbot/bin/python'} 19:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:33 INFO - Running setup.py (path:/tmp/pip-xtly7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:33 INFO - Running setup.py (path:/tmp/pip-03kOXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:33 INFO - Running setup.py (path:/tmp/pip-7QINUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:33 INFO - Running setup.py (path:/tmp/pip-jMZPHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:33 INFO - Running setup.py (path:/tmp/pip-VDPi01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 INFO - Running setup.py (path:/tmp/pip-22xnn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 INFO - Running setup.py (path:/tmp/pip-rckRMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 INFO - Running setup.py (path:/tmp/pip-IVmiGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:33 INFO - Running setup.py (path:/tmp/pip-petv4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:33 INFO - Running setup.py (path:/tmp/pip-NfQxi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:33 INFO - Running setup.py (path:/tmp/pip-eSYpHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:34 INFO - Running setup.py (path:/tmp/pip-i4bvvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:34 INFO - Running setup.py (path:/tmp/pip-JCZSkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:34 INFO - Running setup.py (path:/tmp/pip-0S7fGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:34 INFO - Running setup.py (path:/tmp/pip-nZU1GZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:34 INFO - Running setup.py (path:/tmp/pip-9kE9Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:34 INFO - Running setup.py (path:/tmp/pip-ShjS4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:15:34 INFO - Running setup.py install for manifestparser 19:15:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:15:34 INFO - Running setup.py install for mozcrash 19:15:34 INFO - Running setup.py install for mozdebug 19:15:34 INFO - Running setup.py install for mozdevice 19:15:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:15:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:15:34 INFO - Running setup.py install for mozfile 19:15:34 INFO - Running setup.py install for mozhttpd 19:15:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozinfo 19:15:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozInstall 19:15:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozleak 19:15:35 INFO - Running setup.py install for mozlog 19:15:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for moznetwork 19:15:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozprocess 19:15:35 INFO - Running setup.py install for mozprofile 19:15:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozrunner 19:15:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:15:35 INFO - Running setup.py install for mozscreenshot 19:15:36 INFO - Running setup.py install for moztest 19:15:36 INFO - Running setup.py install for mozversion 19:15:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:15:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15: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')]} 19:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:36 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 19:15:36 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 19:15:36 INFO - Using env: {'DISPLAY': ':0', 19:15:36 INFO - 'HOME': '/home/cltbld', 19:15:36 INFO - 'LANG': 'en_US.UTF-8', 19:15:36 INFO - 'LANGUAGE': 'en_US:en', 19:15:36 INFO - 'LOGNAME': 'cltbld', 19:15:36 INFO - 'MAIL': '/var/mail/cltbld', 19:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:36 INFO - 'MOZ_NO_REMOTE': '1', 19:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:36 INFO - 'NO_EM_RESTART': '1', 19:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:36 INFO - 'PWD': '/builds/slave/test', 19:15:36 INFO - 'SHELL': '/bin/bash', 19:15:36 INFO - 'SHLVL': '1', 19:15:36 INFO - 'TERM': 'linux', 19:15:36 INFO - 'TMOUT': '86400', 19:15:36 INFO - 'USER': 'cltbld', 19:15:36 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:36 INFO - '_': '/tools/buildbot/bin/python'} 19:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:36 INFO - Running setup.py (path:/tmp/pip-hKTSZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:36 INFO - Running setup.py (path:/tmp/pip-8lGncC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:36 INFO - Running setup.py (path:/tmp/pip-BAymqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:36 INFO - Running setup.py (path:/tmp/pip-QKa74H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:36 INFO - Running setup.py (path:/tmp/pip-nZMguk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:36 INFO - Running setup.py (path:/tmp/pip-NbsQLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:36 INFO - Running setup.py (path:/tmp/pip-m5dxVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:36 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)) 19:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:36 INFO - Running setup.py (path:/tmp/pip-qhNylZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:37 INFO - Running setup.py (path:/tmp/pip-E_2ryY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:37 INFO - Running setup.py (path:/tmp/pip-O1kNub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:37 INFO - Running setup.py (path:/tmp/pip-jUjpNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:37 INFO - Running setup.py (path:/tmp/pip-OQ5dEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:37 INFO - Running setup.py (path:/tmp/pip-E5cawS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:37 INFO - Running setup.py (path:/tmp/pip-hT9lIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:37 INFO - Running setup.py (path:/tmp/pip-Aj0l_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:37 INFO - Running setup.py (path:/tmp/pip-UvazDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:37 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)) 19:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:37 INFO - Running setup.py (path:/tmp/pip-I2qgNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 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)) 19:15:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15:37 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 19:15:37 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 19:15:37 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 19:15:37 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 19:15:37 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 19:15:37 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 19:15:39 INFO - Downloading blessings-1.6.tar.gz 19:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:39 INFO - Installing collected packages: blessings 19:15:39 INFO - Running setup.py install for blessings 19:15:39 INFO - Successfully installed blessings 19:15:39 INFO - Cleaning up... 19:15:39 INFO - Return code: 0 19:15:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:40 INFO - Reading from file tmpfile_stdout 19:15:40 INFO - Current package versions: 19:15:40 INFO - argparse == 1.2.1 19:15:40 INFO - blessings == 1.6 19:15:40 INFO - blobuploader == 1.2.4 19:15:40 INFO - docopt == 0.6.1 19:15:40 INFO - functools32 == 3.2.3-2 19:15:40 INFO - jsonschema == 2.5.1 19:15:40 INFO - manifestparser == 1.1 19:15:40 INFO - mozInstall == 1.12 19:15:40 INFO - mozcrash == 0.17 19:15:40 INFO - mozdebug == 0.1 19:15:40 INFO - mozdevice == 0.48 19:15:40 INFO - mozfile == 1.2 19:15:40 INFO - mozhttpd == 0.7 19:15:40 INFO - mozinfo == 0.9 19:15:40 INFO - mozleak == 0.1 19:15:40 INFO - mozlog == 3.2 19:15:40 INFO - moznetwork == 0.27 19:15:40 INFO - mozprocess == 0.23 19:15:40 INFO - mozprofile == 0.28 19:15:40 INFO - mozrunner == 6.12 19:15:40 INFO - mozscreenshot == 0.1 19:15:40 INFO - mozsystemmonitor == 0.3 19:15:40 INFO - moztest == 0.7 19:15:40 INFO - mozversion == 1.4 19:15:40 INFO - psutil == 3.1.1 19:15:40 INFO - requests == 1.2.3 19:15:40 INFO - wsgiref == 0.1.2 19:15:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15: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')]} 19:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:40 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:40 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 19:15:40 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 19:15:40 INFO - Using env: {'DISPLAY': ':0', 19:15:40 INFO - 'HOME': '/home/cltbld', 19:15:40 INFO - 'LANG': 'en_US.UTF-8', 19:15:40 INFO - 'LANGUAGE': 'en_US:en', 19:15:40 INFO - 'LOGNAME': 'cltbld', 19:15:40 INFO - 'MAIL': '/var/mail/cltbld', 19:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:40 INFO - 'MOZ_NO_REMOTE': '1', 19:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:40 INFO - 'NO_EM_RESTART': '1', 19:15:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:40 INFO - 'PWD': '/builds/slave/test', 19:15:40 INFO - 'SHELL': '/bin/bash', 19:15:40 INFO - 'SHLVL': '1', 19:15:40 INFO - 'TERM': 'linux', 19:15:40 INFO - 'TMOUT': '86400', 19:15:40 INFO - 'USER': 'cltbld', 19:15:40 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:40 INFO - '_': '/tools/buildbot/bin/python'} 19:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 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)) 19:15:40 INFO - Cleaning up... 19:15:40 INFO - Return code: 0 19:15:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15: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')]} 19:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:40 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 0x1808030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x196b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', '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 19:15:40 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 19:15:40 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 19:15:40 INFO - Using env: {'DISPLAY': ':0', 19:15:40 INFO - 'HOME': '/home/cltbld', 19:15:40 INFO - 'LANG': 'en_US.UTF-8', 19:15:40 INFO - 'LANGUAGE': 'en_US:en', 19:15:40 INFO - 'LOGNAME': 'cltbld', 19:15:40 INFO - 'MAIL': '/var/mail/cltbld', 19:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:40 INFO - 'MOZ_NO_REMOTE': '1', 19:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:40 INFO - 'NO_EM_RESTART': '1', 19:15:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:40 INFO - 'PWD': '/builds/slave/test', 19:15:40 INFO - 'SHELL': '/bin/bash', 19:15:40 INFO - 'SHLVL': '1', 19:15:40 INFO - 'TERM': 'linux', 19:15:40 INFO - 'TMOUT': '86400', 19:15:40 INFO - 'USER': 'cltbld', 19:15:40 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:40 INFO - '_': '/tools/buildbot/bin/python'} 19:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:40 INFO - Cleaning up... 19:15:40 INFO - Return code: 0 19:15:40 INFO - Running post-action listener: _resource_record_post_action 19:15:40 INFO - Running post-action listener: _start_resource_monitoring 19:15:40 INFO - Starting resource monitoring. 19:15:40 INFO - ##### 19:15:40 INFO - ##### Finished create-virtualenv step (success) 19:15:40 INFO - ##### 19:15:40 INFO - ##### 19:15:40 INFO - ##### Running install step. 19:15:40 INFO - ##### 19:15:40 INFO - Running pre-action listener: _resource_record_pre_action 19:15:40 INFO - Running main action method: install 19:15:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:40 INFO - Reading from file tmpfile_stdout 19:15:40 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:40 INFO - Reading from file tmpfile_stdout 19:15:40 INFO - Output received: 19:15:40 INFO - Usage: mozinstall [options] installer 19:15:40 INFO - Options: 19:15:40 INFO - -h, --help show this help message and exit 19:15:40 INFO - -d DEST, --destination=DEST 19:15:40 INFO - Directory to install application into. [default: 19:15:40 INFO - "/builds/slave/test"] 19:15:40 INFO - --app=APP Application being installed. [default: firefox] 19:15:40 INFO - mkdir: /builds/slave/test/build/application 19:15:40 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'] 19:15:40 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 19:15:57 INFO - Reading from file tmpfile_stdout 19:15:57 INFO - Output received: 19:15:57 INFO - /builds/slave/test/build/application/firefox/firefox 19:15:57 INFO - Running post-action listener: _resource_record_post_action 19:15:57 INFO - ##### 19:15:57 INFO - ##### Finished install step (success) 19:15:57 INFO - ##### 19:15:57 INFO - ##### 19:15:57 INFO - ##### Running run-tests step. 19:15:57 INFO - ##### 19:15:57 INFO - Running pre-action listener: _resource_record_pre_action 19:15:57 INFO - Running main action method: run_tests 19:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:57 INFO - Python 2.7.3 19:15:57 INFO - Return code: 0 19:15:57 INFO - grabbing minidump binary from tooltool 19:15:57 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')]} 19:15:57 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 0x1ac73b0>, '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 0x1ac82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ac8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:57 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 19:15:57 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 19:15:57 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 19:15:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:57 INFO - Return code: 0 19:15:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:15:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:15:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 19:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 19:15:57 INFO - Using env: {'DISPLAY': ':0', 19:15:57 INFO - 'HOME': '/home/cltbld', 19:15:58 INFO - 'LANG': 'en_US.UTF-8', 19:15:58 INFO - 'LANGUAGE': 'en_US:en', 19:15:58 INFO - 'LOGNAME': 'cltbld', 19:15:58 INFO - 'MAIL': '/var/mail/cltbld', 19:15:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:58 INFO - 'MOZ_NO_REMOTE': '1', 19:15:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:58 INFO - 'NO_EM_RESTART': '1', 19:15:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:58 INFO - 'PWD': '/builds/slave/test', 19:15:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:58 INFO - 'SHELL': '/bin/bash', 19:15:58 INFO - 'SHLVL': '1', 19:15:58 INFO - 'TERM': 'linux', 19:15:58 INFO - 'TMOUT': '86400', 19:15:58 INFO - 'USER': 'cltbld', 19:15:58 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898', 19:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:58 INFO - '_': '/tools/buildbot/bin/python'} 19:15:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 19:15:58 INFO - mozversion application_buildid: 20160711163014 19:15:58 INFO - mozversion application_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 19:15:58 INFO - mozversion application_display_name: Nightly 19:15:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:58 INFO - mozversion application_name: Firefox 19:15:58 INFO - mozversion application_remotingname: firefox 19:15:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:15:58 INFO - mozversion application_vendor: Mozilla 19:15:58 INFO - mozversion application_version: 50.0a1 19:15:58 INFO - mozversion platform_buildid: 20160711163014 19:15:58 INFO - mozversion platform_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 19:15:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:15:58 INFO - mozversion platform_version: 50.0a1 19:15:58 INFO - using testdate: 1468289758 19:15:58 INFO - actual date: 1468289758 19:15:58 INFO - starting webserver on 'localhost:43967' 19:15:58 INFO - SUITE-START | Running 2 tests 19:15:58 INFO - TEST-START | dromaeo_css 19:15:58 INFO - operating with platform_type : linux_ 19:15:58 INFO - Initialising browser for dromaeo_css test... 19:15:58 INFO - TEST-INFO | started process 24911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQiDc9g/profile http://localhost:43967/getInfo.html) 19:16:02 INFO - PROCESS | 24911 | __metrics Screen width/height:1600/1200 19:16:02 INFO - PROCESS | 24911 | colorDepth:24 19:16:02 INFO - PROCESS | 24911 | Browser inner width/height: 1024/697 19:16:02 INFO - PROCESS | 24911 | __metrics 19:16:03 INFO - PROCESS | 24911 | 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" 19:16:03 INFO - PROCESS | 24911 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:16:04 INFO - TEST-INFO | 24911: exit 0 19:16:04 INFO - Browser initialized. 19:16:04 INFO - Running cycle 1/1 for dromaeo_css test... 19:16:04 INFO - TEST-INFO | started process 24996 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQiDc9g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:15 INFO - PROCESS | 24996 | RSS: Main: 157179904 19:16:15 INFO - PROCESS | 24996 | 19:17:07 INFO - PROCESS | 24996 | 1468289827279 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] 19:19:15 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43967/tests/dromaeo/cssquery-ext.html) 19:19:16 INFO - PROCESS | 24996 | RSS: Main: 254619648 19:19:16 INFO - PROCESS | 24996 | 19:22:18 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-ext.html (next: http://localhost:43967/tests/dromaeo/cssquery-jquery.html) 19:22:19 INFO - PROCESS | 24996 | RSS: Main: 240488448 19:22:19 INFO - PROCESS | 24996 | 19:25:21 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43967/tests/dromaeo/cssquery-mootools.html) 19:25:22 INFO - PROCESS | 24996 | RSS: Main: 242438144 19:25:22 INFO - PROCESS | 24996 | 19:28:24 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43967/tests/dromaeo/cssquery-prototype.html) 19:28:24 INFO - PROCESS | 24996 | RSS: Main: 241483776 19:28:24 INFO - PROCESS | 24996 | 19:31:27 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43967/tests/dromaeo/cssquery-yui.html) 19:31:28 INFO - PROCESS | 24996 | RSS: Main: 227319808 19:31:28 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | Cycle 1(1): loaded http://localhost:43967/tests/dromaeo/cssquery-yui.html (next: http://localhost:43967/tests/dromaeo/cssquery-dojo.html) 19:34:31 INFO - PROCESS | 24996 | RSS: Main: 291082240 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | __start_tp_report 19:34:31 INFO - PROCESS | 24996 | _x_x_mozilla_page_load 19:34:31 INFO - PROCESS | 24996 | _x_x_mozilla_page_load_details 19:34:31 INFO - PROCESS | 24996 | |i|pagename|runs| 19:34:31 INFO - PROCESS | 24996 | |0;dojo.html;2957.7190166934142;3055.2667359833617;3088.961285551535;3112.4242015227182;3125.3280544682902;5259.158534634459;5285.524302812746;5365.382980957191;5455.945440545594;5463.426340234273;5320.920186197211;5330.093884039713;5382.865428364293;5416.864578385543;5427.9185812212845;4795.568398844103;4904.313396124546;4913.926291105637;4914.827981020665;5112.130937740574;4994.425641051289;4999.625028122887;5024.196128619422;5050.3434553508;5057.418396884369;4862.4408193057725;4870.586000189988;4936.136176169156;4940.8517744467545;4948.1093403187415;4933.432655244621;4960.603151747851;5013.072581572421;5021.698698078127;5047.9747601262125;4762.476127625959;4976.0545496355935;5004.349434573519;5016.924746128811;5055.292259083732;5273.31446911899;5343.118385466393;5361.919571206435;5403;5405.702686352249;5744.396838331957;5760.049591817345;5771.278590176228;5787.50806181475;5788.0739081746715;5577.749001294951;5627.831165065014;5804.216430781833;5835.824925252249;5846.824595262149;5228.947710522884;5335.6798592084215;5338.039152952429;5338.145896656555;5345.38528069277;5839.211706419579;5841.824745257649;5851.297844258716;5853.502452291517;5857.648541087548;5449.155380915964;5527.889442211133;5581.972090139605;5583.832485025456;5584.525315348241;5684.488396044376;5720.027595308808;5739.024365857814;5740.167675687002;5741.368449470555;5595.580331475156;5629.802956896571;5636.04187288162;5638.464345887134;5638.633488823213;5538.75075621598;5674.631148975303;5685;5745.368009518949;5761.567882408836;4391.67062470316;4392.912141757147;4394.406755088086;4412.227860124465;4428.158649856581;3917.3928041153667;3925.685945124383;3938.7045971552247;3971.6028397160053;3998.220347032359;5287.576993840484;5332.280142180756;5341.5192632662465;5372.301600791872;5412.648177868426;5712.171735098387;5721.485066344049;5729.4843464088435;5746.569007324468;5777.566682498829;5418.864528386743;5419.132938729785;5423.240746295522;5423.322084739408;5424.1321388577635;4888.144574699413;4943.9258411123865;4975.676581022222;4993;5004.574611158092;5940.138679891392;5951.553633477506;5957.523398128139;5958.136070269787;5959.433853783884;5619.634723742944;5790.623609465371;5806.2451881255165;5809.738561764646;5810.622309549865;5914.2311499504785;5944.5838791284195;5948.940510594839;5990.221271234712;6011.819645410645;5622.044252477007;5648.830535083954;5649.943500565106;5655.660660360391;5655.830325090254;5588.888222235614;5719.942800572107;5762.078067509178;5763.250777399079;5768.538516918553;5640.83077507659;5695.772169113189;5698.458646428583;5746.310442746897;5748.913766293593;5603.10350343943;5655.604107712421;5656.5757568181725;5656.85857853557;5663.9716801415725;5402.459754024723;5607;5629.4652008059165;5639.238702775073;5642.605017648725;5695.686737229492;5740.0815869460685;5743.59794814359;5778.971105144447;5790.594658373873;5578.414266501959;5610.214569960127;5624.04391253496;5626.043572592751;5628.549716022791 19:34:31 INFO - PROCESS | 24996 | |1;ext.html;590.3506143242433;615.8368032471393;615.987680246395;629.0972454527756;640.5260107520435;34040.31919361611;37425.25149497012;43988.34017489736;44807.551924480744;45052.324215136796;46852.297215541796;47444.52555474444;47500.28749568759;47584.19326453884;47692.284615730794;8171.10117887032;9030.638774449031;9170.358074934757;9174.128457796498;9176.541172941359;10568.15454763621;10825.458727063655;10828.133749300037;11155.163362747786;11156.721081972935;11069.335839849635;11166.050885674727;11170.441477926071;11178.496967636434;11181.552737890475;8259.091499935003;10622.57509699611;10917.23579349446;10938.343699378023;11008.39453830035;10793.460326983619;10858.891411085868;10874;10881.183911206612;10882.72793180169;43400.565994340286;43740.781296093315;43745;43799.56200438018;43809.12381752379;2835.036087730161;3532.982335088308;3585.372559802024;3706.5181526401393;3888.4556162137324;33893.322133557194;33946.321073578634;34449.827750861084;34515.482267766005;35236;4408.008198155421;4503.572160644733;4553.840615578472;4592.380028696117;4743.312219728155;377165;377414;378922;379151;379615;34431.13922151966;62129.37870621236;62464.37535624586;62561.37438625556;62578.687106564175;35898.82050589783;36950.63049369472;37550.24899502049;37551;37570.81214593964;15521.37914483408;15577.688446231012;15704.842951570567;15709.371625134867;15710.214489275719;11826.940865295619;11842.940785296018;11888.762224755457;11893.108016898766;11895.583654572049;8459;8497.36269779769;8501.957490212633;8560.657573696983;8566.957165214259;3075.7385622222364;3194.632617249;3230.305484320835;3283.3433313337428;3320.867165313409;995.382862625177;1044.5612842606124;1064.3986147826454;1165.4231155577875;1172.8240763885315;32685.673143268737;33174.3365132696;33330;33366.666333336354;33564.832175838965;1144.5879483385975;1159.5072094359864;1215.3254943506438;1216.2823933879054;1217.72601164739;17186.31254749796;17906.5344450019;18026.909865450853;18059.27762889496;18430.815691843178;555570;559176;560833;561094;561503;59444.10833837496;61713;61716.69141654263;61755.6912215436;61772;1222.7371115210271;1347.730453909222;1368.9383977721027;1370.205280937054;1411.9929400352933;32650.83674581707;34357;34641.67816011806;34667.13332166716;34712.65287347094;52303.215451769014;53132.46867531275;53146.20280695715;53159.73420132875;53173.734131329096;456781;460331;461009;461622;462233;24885;25834.61248081318;28613;28665.14004579866;29563.26091847721;48141.27788083111;48164.518354816006;48314.75842620905;48868.2669759961;49034.264486033455;41670.79164604279;42560.78719606382;42594.57405425906;42671.573284268;42813.35779963365;40956;41346.379804302356;41358.58641413668;41508.377374340016;41878.79060604677;40845.79577102095;41405;41741.79129104335;42197.15605687931;42247.57752422559;35500.64499355077;37132.25735485221;37559.81220093882;37832.243355133294;37980;28150.29624259328;28286.57961372276;28696;28727.856360718062;28740.712592873802 19:34:31 INFO - PROCESS | 24996 | |2;jquery.html;397.2729904275177;401.161572313864;403.93133167361543;404.88865561970465;416.8020190409554;13315.933420332885;18962.620747585057;20764.480887977807;20770.169193232272;21715;14768.926155369236;19186.136623851926;19207.807921920776;20276.29032983846;20520.38438846837;5687.402822703619;5856.707164641773;5857.238558987326;5859.824205273827;5881.235439392873;5706.942930570683;5757.856053598652;5772.509336706385;5775.884482310351;5781.508571771405;4155.33514637658;6120.081987701836;6412.230532336126;6425.261094974067;6517.087607734921;6187.443130118311;6376.744930202786;6468.32082631324;6477.352264773555;6500.707468163921;5591.4688104629995;5612.15817627355;5620.100783874561;5625.240592519998;5626.465485778845;23598.88200558986;24337.756622433728;24542.754572454225;24646.383840404134;25011.49977000468;2468.987655061731;2521.15541293665;2523.8990440382363;2538.6445897574354;2544.643749875019;13670.52153174634;14682.265886705622;14814.555563333117;15057.096574205472;15459.690806183813;1949.4249196487071;1986.384220891514;2041.7039529268172;2098.95802083959;2106.452322396173;83701.58149209215;84150.15849841485;85270;87058.56470717606;87739;10245.463180522982;10353.171746260281;10916.399598022184;11022.173336999756;11132.380062274227;15189;15271.160086195367;15302.158381289137;15302.311395987208;15305.540833775005;8051.154628764012;8503.787405314917;8515.74452766418;8570.442921210102;8621.741347759576;8241.670333186605;8271.462354946909;8329.625166867505;8488.702895398632;8489.660413583511;6015.653894980961;6276.717547710273;6348.079528468346;6670.833229169212;6848.212455567574;1812.5196822841958;1822.7812662480321;1845.7877344105332;1859.414284500378;1902.980970190308;932.2122806228726;988.6935050134481;989.3569180033036;997.9750506237402;1079.4548752879903;20132.584089909305;20682.276120335715;20724.378268651966;20762;20927.68608470874;1031.0926384781344;1039.6828967164936;1083.8265877459569;1086.462201210406;1093.1801149138146;11622.88377116235;11915.640774955551;11939.223950443193;11954.76090478203;12048;473723;475889;476105;482391;500664;85069;86181;86363;87210;87448;981.2101258486844;991.0436428846198;996.9841230278321;1019.2915923433143;1029.5006921642996;18170.27318907229;18170.81829181691;18515.166817493515;18527.629447410705;18897.43307700771;39093.41359879547;41941;42789.14421711486;42996.35505467358;43195.56804431915;49778.751106244235;60713.69643151756;61356.69321653363;62110;63316.050259245225;7805.375569954504;8465.111163327761;8661.437006594802;8809.691660792098;8907.10928907104;11969.581064662694;13694.52069177574;14306.356213970605;14417.279136043366;14461;11734.119941004288;11833.408329583659;11966.162368634112;12283;12508.499660013498;12009;12051.337176455028;12103.455344509694;12163.706502048479;12204.572839950559;12487.812682809583;12491.93754031224;12537.247765134121;12806.359682015676;12894.161879477804;8223.136570660234;8247.257746802816;8254.876176857231;8314.916850831656;8337.791555211168;10770.676879693621;11753.647390578297;11853.051755859338;12235.877641223475;12526.624201273977 19:34:31 INFO - PROCESS | 24996 | |3;mootools.html;1342.3757253722995;1482.5922871210416;1505.2548988250817;1643.2605327602575;1812.9184186711589;8729.869051964217;9242;9244.44533328;9244.44533328;9254.444733316006;9007.054259302777;9222.21611163052;9240.630374785018;9243.861342079874;9251.44491330519;2033.2273735980307;2293.048384920256;2360.350903501538;2374.02664907388;2403.230966090852;2362.1850461590725;2376.6197408414655;2377.5720370333333;2379.0840526397287;2381.6546600742968;2332.9533409331802;2374.228375777879;2394.4971555973266;2399.9040038398443;2400.4958958618713;2030.8883011434366;2203.5703037907615;2253.5943530164564;2277.225743247304;2285.5771682238837;2281.3270085324702;2297.6094064009158;2304.5390921815538;2304.9078036878504;2319.88400579972;9298.349115561914;9338.486383248919;9357.859632105525;9379.484128372937;9416.482093484856;5340.652857564246;5493.093639549509;5498.037843377393;5499.50504454597;5501.037318469252;3515.6640476618722;3518.6833185013215;3564.1624218308675;3569.7322700797545;3574.7318951078773;1182.3142577305143;1185.146694380045;1196.2822306616047;1213.9028877689764;1215.4591206912928;13169.670758231025;13203.207807531579;13217.33913304331;13244.602661920158;13256.46974121034;4702.694324868941;4734.100520901018;4770.964873497874;4792.305115758196;4941.505849415102;10927.890721092846;10975.067119294948;10976.835347469794;10988.395638239974;10996.670099896855;5357.919631205535;5378.435264297193;5389.137737961985;5403.432639572867;5405;4392.868213953587;4811.9518804811505;4968.254761785761;4970.478099799541;5034.295198672189;4071.776052317151;4107.404426358161;4185.183889141588;4213.4522512073845;4244.554321796229;1112.2158877990996;1206.1436380170178;1271.7011502296953;1313.9868601313865;1327.6680829792551;840.932725381968;999.3254553176577;999.9850002249972;1015.827309357411;1029.629333439961;6028.703828676856;6171.722272497748;6195.256569211633;6195.628262304276;6273.309935907047;985.8767654043245;988.5057471264367;994.9403035817874;999.5102399824034;1010.080866296404;4181.435506206684;4238.088810905609;4275.1022285319805;4292.871213863589;4361.651067914623;11034.944825275823;11431.256968297033;11527.423628818693;11538.942305288589;11550.075993920465;11355.205135640426;11372.886271137182;11378.31730096197;11395;11401.372924489233;8095.959520202361;8384;8419.494830310201;8460.450070745383;8547.914520854836;6232.407921247474;6414.069700819681;6437.774677886251;6553.967230163818;6580.374864387875;10340.53467593945;10657.520411581652;10712.464376781285;10721.517531711248;10753.085987691135;10577.576896924036;10805.837912431478;10814.243002989715;10954.56181752721;11000.614978475714;7861.292483676497;8400.663973441238;8428.536430496382;8667.133286671282;8716.651333946571;8876.77808054778;9067.637294508146;9113.817723645623;9135.862962055708;9183.77040573991;7828.473447677648;7863.882041769492;7988.960055199686;8053.919460805551;8208.097109318089;7744.5740484271655;7834.764957051298;7839.294463498312;7902.644381002984;8162.551059691774;7500.887486687595;7536.020317358708;7622.31399174077;7623.885641715489;7628.54228746277;5445.401005889428;5453.4819192178265;5473;5561.916571251353;5626.396477004034;5616.129499927518;5705.686187259575;5784.623999440191;5828.94171058284;5832.795852145155 19:34:31 INFO - PROCESS | 24996 | |4;prototype.html;335.63396974314537;339.13858798651427;345.3817666377185;348.27558677949867;352.4554563199856;5255.894882102356;5363.89272214556;5407.324084489439;5415.241866138739;5439.368189543136;5185.792568297264;5244.265802787603;5257;5296.496832800878;5307.336582927134;4189.9790501047455;4196.999145610887;4212.873613791591;4237.173751118533;4238.173556156551;4163.916721665565;4201.075763332062;4205.285101532747;4242.215190189825;4254.212970600433;4155.584441555835;4180.327868852453;4181.477315335583;4182.707210495267;4195.307774217251;4159.854405095823;4189.748615083075;4195.475565554306;4204.096119334357;4213.641840443566;3762.830672619739;3766.773993560395;3766.9623303766884;3768.6419790119894;3783.9783258520047;4981.227909674007;5238.214267859814;5246.0032593807055;5247.816326428557;5269.868253293698;4819.542143496362;4825.758712064383;4826.469088400273;4828.348172996635;4853.344798452199;5341.732913354317;5619.269494965628;5621.690807005613;5640.153976903456;5697.430256974311;4072.348424252135;4074.5517993020744;4080.5511393746665;4084.305668036441;4095.4471146395153;5856.941430585724;5885.411458854123;5890.64515161511;5902.4097590240635;5916.455923952699;3987.6809855212095;4181.707280490397;4186.623203911662;4194.601512856274;4196.9370459443135;4186.099988502426;4188.434561334242;4191.350340697197;4198.5591512891315;4202.138561594866;4716.85849424511;4747.264174053027;4759.785809638574;4776.522347765195;4803.663743537989;5346.492083252085;5540.168974653753;5540.612157149041;5557.277553918046;5637.520810731134;1368.7673095573775;1408.6267139208119;1436.2029073864105;1441.8486058963658;1442.9062110962755;966.1449617088787;974.4299584742863;984.950752462374;987.151050096916;1000.3697670467559;5341.919871201935;5385.192221166856;5392.703401312965;5415.241866138744;5434.375046869582;5651.745671444713;5670.177824215466;5672.773089076473;5682.232898558725;5684.175794509855;5179.870503237374;5338.492843179892;5349.946500534945;5383.084875571162;5437.809676661298;2666.8133230673666;2669.4927963686837;2676.1168814291236;2677.250369896432;2692.0846912049997;5184.248283998799;5237.795307079316;5249.816256431083;5300.496452836975;5300.602454815904;5233.676615349281;5476.26070480488;5841.240638716939;5854.302252346772;5875.118732190205;5905.940940590625;5927.555433342516;5948.917878742405;5965.761369545169;5976.342602313655;5607.43925607436;5797.565182611405;5857.4435428634215;5857.970710146422;5885.411458854081;5677.6657485491705;5704.629199102045;5709.857253568694;5716.485516303553;5725.599208055396;5864.648121112747;5926.621825559389;5994.610350327215;5995.760169593342;6017.157597936205;3941.3890846918775;4122.896927576835;4141.544430112745;4155.542890282127;4193.748375097504;5582.748776305158;5732.656040637575;5788.7395067222915;5801.071828507418;5820.679862607597;5514.420985796433;5593.272874526286;5601.271834661468;5610.961972035187;5636.267285253055;5610.382857885547;5618.7190640467;5627.521660658726;5642.774289028393;5661.235733175969;5429.972850135724;5597.384287728428;5673.092305231144;5673.092305231144;5690.118031705092;5681.744321505625;5710.400407957105;5711.628744131618;5715.114157305624;5731.942680573141;5483.561315094863;5603.859903502445;5652.378238393856;5661.801836935688;5675.177099320658 19:34:31 INFO - PROCESS | 24996 | |5;yui.html;824.1717074340287;829.4981536170617;840.7351684219474;866.2377108144833;874.169538938009;15182.468613598512;15840.445584404548;15871.920640396782;15872.285747141392;15875.761863572056;13916.304184790746;13951.441942322295;13956;13968.930155349235;13969.930150349233;801.1827935505795;822.8066404394963;824.9958750206241;825.9669613215494;826.1531929771971;663.4493370502496;791.9643616037264;803.2971150243537;804.2761514636844;805.9274665280124;870.3428911172056;873.8164985353084;876.4609764994515;876.7238319929202;876.9298456123495;864.464032299978;866.1511718515827;866.1728049712541;866.2204016385241;866.7876370289288;509.5923261390872;608.6348191085358;628.5662892604089;629.8866204083263;632.1623848400841;15249.08505489663;15351.923240383727;15375.154366509838;15386.769198462032;15407.922960385238;14262.144271343752;14331.713365732627;14409.847212223101;14827.77758333626;15068.924655376653;13096.345182740824;13365;13412.12821166621;13623.659408514866;14116.223607701573;4552.362669226311;4565.6347492200875;4572.839950601746;4573.862784116482;4765.26138448541;73282;74410.25589744087;74505.62747186229;74645.25354746437;75387.6230618849;11134.27627204229;11930.821037684442;12037.87962120385;12135.87864121365;12449.626511204679;20024;21303.573928521342;21329.25347612857;21332.893335533532;21336.466588335104;8219.219174178443;8821;8834.55827208637;8842.381033327605;8872.600732967032;5052.5705315048635;5108.514691104339;5135.97432012845;5138.87152821175;5141.742912854343;3473.6179020307745;3619.638036196412;3645.872394466181;3647.325244829715;3652.6712595866497;642.4773287309107;644.0725355488175;649.7368565730886;659.6437923521245;667.2926697700356;566.940471250521;668.9899651505232;703.8345988692653;763.522798251093;765.7243392378739;9178.638226749938;9462.432254064779;9553.140217380469;9616.423014619146;9687.17658998979;706.5372181221309;709.1490211745926;724.1165777751136;726.2338233164063;748.0686545251089;4598.965232822588;4762.472787999856;4873.074115917964;4925.11347957364;5222.321098257202;111930.44034779775;130805.34597326952;132781.3360933189;133550;137419.31290343683;33604.64767466254;33691.326173476395;62543;82834.17165828386;107540.46229768958;69267.65366173137;74529;99542.5022874881;100917.49541252246;102346;10646.574137034431;10694.899018120821;10877;10916;11004.889951100387;40536.94630536923;53988.19017714816;54450.727746361015;55161.72419137879;56992.30076992433;84073.57963210145;88515;90107.54946225227;90579;90673.54663226905;12446.298764685856;12598.181118227287;12712.936435317764;12730.68173295675;12963.15739476931;16347.346106156096;16351.427700030441;16353;16841.494755156873;17033.23350449208;13136.737265254833;13420.46318147263;13506;13875.236861972688;14488.348024338718;14593.781093283822;14599.781003284746;15437.691246174789;15555.45555905538;15738.770803094088;14232;14366.209858457885;14651.706965860836;14718.411263549338;15159.317830697424;11919.642410727693;11998.220115692453;12120.878791211975;12310.446029928853;12505.56230531927;11141.859228823856;11545.769084618092;11805.527778888749;12296.262224266573;12310.199837010565 19:34:31 INFO - PROCESS | 24996 | __end_tp_report 19:34:31 INFO - PROCESS | 24996 | __start_cc_report 19:34:31 INFO - PROCESS | 24996 | _x_x_mozilla_cycle_collect,810 19:34:31 INFO - PROCESS | 24996 | __end_cc_report 19:34:31 INFO - PROCESS | 24996 | __startTimestamp1468290871588__endTimestamp 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | ------- Summary: start ------- 19:34:31 INFO - PROCESS | 24996 | Number of tests: 6 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#0] dojo.html Cycles:165 Average:5346.01 Median:5583.83 stddev:599.63 (10.7%) stddev-sans-first:571.45 19:34:31 INFO - PROCESS | 24996 | Values: 2957.7 3055.3 3089.0 3112.4 3125.3 5259.2 5285.5 5365.4 5455.9 5463.4 5320.9 5330.1 5382.9 5416.9 5427.9 4795.6 4904.3 4913.9 4914.8 5112.1 4994.4 4999.6 5024.2 5050.3 5057.4 4862.4 4870.6 4936.1 4940.9 4948.1 4933.4 4960.6 5013.1 5021.7 5048.0 4762.5 4976.1 5004.3 5016.9 5055.3 5273.3 5343.1 5361.9 5403.0 5405.7 5744.4 5760.0 5771.3 5787.5 5788.1 5577.7 5627.8 5804.2 5835.8 5846.8 5228.9 5335.7 5338.0 5338.1 5345.4 5839.2 5841.8 5851.3 5853.5 5857.6 5449.2 5527.9 5582.0 5583.8 5584.5 5684.5 5720.0 5739.0 5740.2 5741.4 5595.6 5629.8 5636.0 5638.5 5638.6 5538.8 5674.6 5685.0 5745.4 5761.6 4391.7 4392.9 4394.4 4412.2 4428.2 3917.4 3925.7 3938.7 3971.6 3998.2 5287.6 5332.3 5341.5 5372.3 5412.6 5712.2 5721.5 5729.5 5746.6 5777.6 5418.9 5419.1 5423.2 5423.3 5424.1 4888.1 4943.9 4975.7 4993.0 5004.6 5940.1 5951.6 5957.5 5958.1 5959.4 5619.6 5790.6 5806.2 5809.7 5810.6 5914.2 5944.6 5948.9 5990.2 6011.8 5622.0 5648.8 5649.9 5655.7 5655.8 5588.9 5719.9 5762.1 5763.3 5768.5 5640.8 5695.8 5698.5 5746.3 5748.9 5603.1 5655.6 5656.6 5656.9 5664.0 5402.5 5607.0 5629.5 5639.2 5642.6 5695.7 5740.1 5743.6 5779.0 5790.6 5578.4 5610.2 5624.0 5626.0 5628.5 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#1] ext.html Cycles:180 Average:61935.96 Median:37555.41 stddev:125388.51 (333.9%) stddev-sans-first:125655.66 19:34:31 INFO - PROCESS | 24996 | Values: 590.4 615.8 616.0 629.1 640.5 34040.3 37425.3 43988.3 44807.6 45052.3 46852.3 47444.5 47500.3 47584.2 47692.3 8171.1 9030.6 9170.4 9174.1 9176.5 10568.2 10825.5 10828.1 11155.2 11156.7 11069.3 11166.1 11170.4 11178.5 11181.6 8259.1 10622.6 10917.2 10938.3 11008.4 10793.5 10858.9 10874.0 10881.2 10882.7 43400.6 43740.8 43745.0 43799.6 43809.1 2835.0 3533.0 3585.4 3706.5 3888.5 33893.3 33946.3 34449.8 34515.5 35236.0 4408.0 4503.6 4553.8 4592.4 4743.3 377165.0 377414.0 378922.0 379151.0 379615.0 34431.1 62129.4 62464.4 62561.4 62578.7 35898.8 36950.6 37550.2 37551.0 37570.8 15521.4 15577.7 15704.8 15709.4 15710.2 11826.9 11842.9 11888.8 11893.1 11895.6 8459.0 8497.4 8502.0 8560.7 8567.0 3075.7 3194.6 3230.3 3283.3 3320.9 995.4 1044.6 1064.4 1165.4 1172.8 32685.7 33174.3 33330.0 33366.7 33564.8 1144.6 1159.5 1215.3 1216.3 1217.7 17186.3 17906.5 18026.9 18059.3 18430.8 555570.0 559176.0 560833.0 561094.0 561503.0 59444.1 61713.0 61716.7 61755.7 61772.0 1222.7 1347.7 1368.9 1370.2 1412.0 32650.8 34357.0 34641.7 34667.1 34712.7 52303.2 53132.5 53146.2 53159.7 53173.7 456781.0 460331.0 461009.0 461622.0 462233.0 24885.0 25834.6 28613.0 28665.1 29563.3 48141.3 48164.5 48314.8 48868.3 49034.3 41670.8 42560.8 42594.6 42671.6 42813.4 40956.0 41346.4 41358.6 41508.4 41878.8 40845.8 41405.0 41741.8 42197.2 42247.6 35500.6 37132.3 37559.8 37832.2 37980.0 28150.3 28286.6 28696.0 28727.9 28740.7 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#2] jquery.html Cycles:180 Average:29199.82 Median:2531.27 stddev:79404.47 (3136.9%) stddev-sans-first:79597.77 19:34:31 INFO - PROCESS | 24996 | Values: 397.3 401.2 403.9 404.9 416.8 13315.9 18962.6 20764.5 20770.2 21715.0 14768.9 19186.1 19207.8 20276.3 20520.4 5687.4 5856.7 5857.2 5859.8 5881.2 5706.9 5757.9 5772.5 5775.9 5781.5 4155.3 6120.1 6412.2 6425.3 6517.1 6187.4 6376.7 6468.3 6477.4 6500.7 5591.5 5612.2 5620.1 5625.2 5626.5 23598.9 24337.8 24542.8 24646.4 25011.5 2469.0 2521.2 2523.9 2538.6 2544.6 13670.5 14682.3 14814.6 15057.1 15459.7 1949.4 1986.4 2041.7 2099.0 2106.5 83701.6 84150.2 85270.0 87058.6 87739.0 10245.5 10353.2 10916.4 11022.2 11132.4 15189.0 15271.2 15302.2 15302.3 15305.5 8051.2 8503.8 8515.7 8570.4 8621.7 8241.7 8271.5 8329.6 8488.7 8489.7 6015.7 6276.7 6348.1 6670.8 6848.2 1812.5 1822.8 1845.8 1859.4 1903.0 932.2 988.7 989.4 998.0 1079.5 20132.6 20682.3 20724.4 20762.0 20927.7 1031.1 1039.7 1083.8 1086.5 1093.2 11622.9 11915.6 11939.2 11954.8 12048.0 473723.0 475889.0 476105.0 482391.0 500664.0 85069.0 86181.0 86363.0 87210.0 87448.0 981.2 991.0 997.0 1019.3 1029.5 18170.3 18170.8 18515.2 18527.6 18897.4 39093.4 41941.0 42789.1 42996.4 43195.6 49778.8 60713.7 61356.7 62110.0 63316.1 7805.4 8465.1 8661.4 8809.7 8907.1 11969.6 13694.5 14306.4 14417.3 14461.0 11734.1 11833.4 11966.2 12283.0 12508.5 12009.0 12051.3 12103.5 12163.7 12204.6 12487.8 12491.9 12537.2 12806.4 12894.2 8223.1 8247.3 8254.9 8314.9 8337.8 10770.7 11753.6 11853.1 12235.9 12526.6 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#3] mootools.html Cycles:180 Average:6093.55 Median:4752.53 stddev:3515.96 (74.0%) stddev-sans-first:3507.69 19:34:31 INFO - PROCESS | 24996 | Values: 1342.4 1482.6 1505.3 1643.3 1812.9 8729.9 9242.0 9244.4 9244.4 9254.4 9007.1 9222.2 9240.6 9243.9 9251.4 2033.2 2293.0 2360.4 2374.0 2403.2 2362.2 2376.6 2377.6 2379.1 2381.7 2333.0 2374.2 2394.5 2399.9 2400.5 2030.9 2203.6 2253.6 2277.2 2285.6 2281.3 2297.6 2304.5 2304.9 2319.9 9298.3 9338.5 9357.9 9379.5 9416.5 5340.7 5493.1 5498.0 5499.5 5501.0 3515.7 3518.7 3564.2 3569.7 3574.7 1182.3 1185.1 1196.3 1213.9 1215.5 13169.7 13203.2 13217.3 13244.6 13256.5 4702.7 4734.1 4771.0 4792.3 4941.5 10927.9 10975.1 10976.8 10988.4 10996.7 5357.9 5378.4 5389.1 5403.4 5405.0 4392.9 4812.0 4968.3 4970.5 5034.3 4071.8 4107.4 4185.2 4213.5 4244.6 1112.2 1206.1 1271.7 1314.0 1327.7 840.9 999.3 1000.0 1015.8 1029.6 6028.7 6171.7 6195.3 6195.6 6273.3 985.9 988.5 994.9 999.5 1010.1 4181.4 4238.1 4275.1 4292.9 4361.7 11034.9 11431.3 11527.4 11538.9 11550.1 11355.2 11372.9 11378.3 11395.0 11401.4 8096.0 8384.0 8419.5 8460.5 8547.9 6232.4 6414.1 6437.8 6554.0 6580.4 10340.5 10657.5 10712.5 10721.5 10753.1 10577.6 10805.8 10814.2 10954.6 11000.6 7861.3 8400.7 8428.5 8667.1 8716.7 8876.8 9067.6 9113.8 9135.9 9183.8 7828.5 7863.9 7989.0 8053.9 8208.1 7744.6 7834.8 7839.3 7902.6 8162.6 7500.9 7536.0 7622.3 7623.9 7628.5 5445.4 5453.5 5473.0 5561.9 5626.4 5616.1 5705.7 5784.6 5828.9 5832.8 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#4] prototype.html Cycles:180 Average:4710.66 Median:5341.83 stddev:1385.84 (25.9%) stddev-sans-first:1350.26 19:34:31 INFO - PROCESS | 24996 | Values: 335.6 339.1 345.4 348.3 352.5 5255.9 5363.9 5407.3 5415.2 5439.4 5185.8 5244.3 5257.0 5296.5 5307.3 4190.0 4197.0 4212.9 4237.2 4238.2 4163.9 4201.1 4205.3 4242.2 4254.2 4155.6 4180.3 4181.5 4182.7 4195.3 4159.9 4189.7 4195.5 4204.1 4213.6 3762.8 3766.8 3767.0 3768.6 3784.0 4981.2 5238.2 5246.0 5247.8 5269.9 4819.5 4825.8 4826.5 4828.3 4853.3 5341.7 5619.3 5621.7 5640.2 5697.4 4072.3 4074.6 4080.6 4084.3 4095.4 5856.9 5885.4 5890.6 5902.4 5916.5 3987.7 4181.7 4186.6 4194.6 4196.9 4186.1 4188.4 4191.4 4198.6 4202.1 4716.9 4747.3 4759.8 4776.5 4803.7 5346.5 5540.2 5540.6 5557.3 5637.5 1368.8 1408.6 1436.2 1441.8 1442.9 966.1 974.4 985.0 987.2 1000.4 5341.9 5385.2 5392.7 5415.2 5434.4 5651.7 5670.2 5672.8 5682.2 5684.2 5179.9 5338.5 5349.9 5383.1 5437.8 2666.8 2669.5 2676.1 2677.3 2692.1 5184.2 5237.8 5249.8 5300.5 5300.6 5233.7 5476.3 5841.2 5854.3 5875.1 5905.9 5927.6 5948.9 5965.8 5976.3 5607.4 5797.6 5857.4 5858.0 5885.4 5677.7 5704.6 5709.9 5716.5 5725.6 5864.6 5926.6 5994.6 5995.8 6017.2 3941.4 4122.9 4141.5 4155.5 4193.7 5582.7 5732.7 5788.7 5801.1 5820.7 5514.4 5593.3 5601.3 5611.0 5636.3 5610.4 5618.7 5627.5 5642.8 5661.2 5430.0 5597.4 5673.1 5673.1 5690.1 5681.7 5710.4 5711.6 5715.1 5731.9 5483.6 5603.9 5652.4 5661.8 5675.2 19:34:31 INFO - PROCESS | 24996 | 19:34:31 INFO - PROCESS | 24996 | [#5] yui.html Cycles:180 Average:20975.83 Median:22544.65 stddev:30657.68 (136.0%) stddev-sans-first:30706.34 19:34:31 INFO - PROCESS | 24996 | Values: 824.2 829.5 840.7 866.2 874.2 15182.5 15840.4 15871.9 15872.3 15875.8 13916.3 13951.4 13956.0 13968.9 13969.9 801.2 822.8 825.0 826.0 826.2 663.4 792.0 803.3 804.3 805.9 870.3 873.8 876.5 876.7 876.9 864.5 866.2 866.2 866.2 866.8 509.6 608.6 628.6 629.9 632.2 15249.1 15351.9 15375.2 15386.8 15407.9 14262.1 14331.7 14409.8 14827.8 15068.9 13096.3 13365.0 13412.1 13623.7 14116.2 4552.4 4565.6 4572.8 4573.9 4765.3 73282.0 74410.3 74505.6 74645.3 75387.6 11134.3 11930.8 12037.9 12135.9 12449.6 20024.0 21303.6 21329.3 21332.9 21336.5 8219.2 8821.0 8834.6 8842.4 8872.6 5052.6 5108.5 5136.0 5138.9 5141.7 3473.6 3619.6 3645.9 3647.3 3652.7 642.5 644.1 649.7 659.6 667.3 566.9 669.0 703.8 763.5 765.7 9178.6 9462.4 9553.1 9616.4 9687.2 706.5 709.1 724.1 726.2 748.1 4599.0 4762.5 4873.1 4925.1 5222.3 111930.4 130805.3 132781.3 133550.0 137419.3 33604.6 33691.3 62543.0 82834.2 107540.5 69267.7 74529.0 99542.5 100917.5 102346.0 10646.6 10694.9 10877.0 10916.0 11004.9 40536.9 53988.2 54450.7 55161.7 56992.3 84073.6 88515.0 90107.5 90579.0 90673.5 12446.3 12598.2 12712.9 12730.7 12963.2 16347.3 16351.4 16353.0 16841.5 17033.2 13136.7 13420.5 13506.0 13875.2 14488.3 14593.8 14599.8 15437.7 15555.5 15738.8 14232.0 14366.2 14651.7 14718.4 15159.3 11919.6 11998.2 12120.9 12310.4 12505.6 11141.9 11545.8 11805.5 12296.3 12310.2 19:34:31 INFO - PROCESS | 24996 | -------- Summary: end -------- 19:34:31 INFO - PROCESS | 24996 | 19:34:32 INFO - TEST-INFO | 24996: exit 0 19:34:32 INFO - TEST-OK | dromaeo_css | took 1114169ms 19:34:32 INFO - TEST-START | kraken 19:34:32 INFO - operating with platform_type : linux_ 19:34:32 INFO - Initialising browser for kraken test... 19:34:32 INFO - TEST-INFO | started process 25189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGmT1IJ/profile http://localhost:43967/getInfo.html) 19:34:36 INFO - PROCESS | 25189 | __metrics Screen width/height:1600/1200 19:34:36 INFO - PROCESS | 25189 | colorDepth:24 19:34:36 INFO - PROCESS | 25189 | Browser inner width/height: 1024/697 19:34:36 INFO - PROCESS | 25189 | __metrics 19:34:37 INFO - PROCESS | 25189 | 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" 19:34:37 INFO - PROCESS | 25189 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:37 INFO - PROCESS | 25189 | console.error: PushService: 19:34:37 INFO - PROCESS | 25189 | stateChangeProcessEnqueue: Error transitioning state 19:34:37 INFO - PROCESS | 25189 | UnknownError 19:34:38 INFO - TEST-INFO | 25189: exit 0 19:34:38 INFO - Browser initialized. 19:34:38 INFO - Running cycle 1/1 for kraken test... 19:34:38 INFO - TEST-INFO | started process 25270 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGmT1IJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:49 INFO - PROCESS | 25270 | RSS: Main: 157384704 19:34:49 INFO - PROCESS | 25270 | 19:34:52 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43967/tests/kraken/driver.html?testName=audio-beat-detection) 19:34:53 INFO - PROCESS | 25270 | RSS: Main: 215871488 19:34:53 INFO - PROCESS | 25270 | 19:34:55 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43967/tests/kraken/driver.html?testName=audio-dft) 19:34:56 INFO - PROCESS | 25270 | RSS: Main: 204087296 19:34:56 INFO - PROCESS | 25270 | 19:34:59 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43967/tests/kraken/driver.html?testName=audio-fft) 19:35:00 INFO - PROCESS | 25270 | RSS: Main: 269574144 19:35:00 INFO - PROCESS | 25270 | 19:35:02 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43967/tests/kraken/driver.html?testName=audio-oscillator) 19:35:02 INFO - PROCESS | 25270 | RSS: Main: 180150272 19:35:02 INFO - PROCESS | 25270 | 19:35:05 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43967/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:35:05 INFO - PROCESS | 25270 | RSS: Main: 184000512 19:35:05 INFO - PROCESS | 25270 | 19:35:08 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43967/tests/kraken/driver.html?testName=imaging-darkroom) 19:35:09 INFO - PROCESS | 25270 | RSS: Main: 219742208 19:35:09 INFO - PROCESS | 25270 | 19:35:12 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43967/tests/kraken/driver.html?testName=imaging-desaturate) 19:35:13 INFO - PROCESS | 25270 | RSS: Main: 221298688 19:35:13 INFO - PROCESS | 25270 | 19:35:16 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43967/tests/kraken/driver.html?testName=json-parse-financial) 19:35:16 INFO - PROCESS | 25270 | RSS: Main: 221741056 19:35:16 INFO - PROCESS | 25270 | 19:35:18 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43967/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:35:18 INFO - PROCESS | 25270 | RSS: Main: 209215488 19:35:18 INFO - PROCESS | 25270 | 19:35:20 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:35:21 INFO - PROCESS | 25270 | RSS: Main: 213880832 19:35:21 INFO - PROCESS | 25270 | 19:35:23 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:35:23 INFO - PROCESS | 25270 | RSS: Main: 211193856 19:35:23 INFO - PROCESS | 25270 | 19:35:26 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:35:26 INFO - PROCESS | 25270 | RSS: Main: 224063488 19:35:26 INFO - PROCESS | 25270 | 19:35:29 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:35:30 INFO - PROCESS | 25270 | RSS: Main: 224497664 19:35:30 INFO - PROCESS | 25270 | 19:35:31 INFO - PROCESS | 25270 | Cycle 1(1): loaded http://localhost:43967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43967/tests/kraken/driver.html?testName=ai-astar) 19:35:32 INFO - PROCESS | 25270 | RSS: Main: 266883072 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | __start_tp_report 19:35:32 INFO - PROCESS | 25270 | _x_x_mozilla_page_load 19:35:32 INFO - PROCESS | 25270 | _x_x_mozilla_page_load_details 19:35:32 INFO - PROCESS | 25270 | |i|pagename|runs| 19:35:32 INFO - PROCESS | 25270 | |0;ai-astar;101;113;110;109;111;110;111;111;111;110 19:35:32 INFO - PROCESS | 25270 | |1;audio-beat-detection;144;120;120;175;118;118;143;118;177;118 19:35:32 INFO - PROCESS | 25270 | |2;audio-dft;161;163;159;159;159;160;160;160;160;160 19:35:32 INFO - PROCESS | 25270 | |3;audio-fft;75;75;75;132;76;75;75;74;107;75 19:35:32 INFO - PROCESS | 25270 | |4;audio-oscillator;83;83;112;84;84;83;120;83;84;124 19:35:32 INFO - PROCESS | 25270 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;117;116;117 19:35:32 INFO - PROCESS | 25270 | |6;imaging-darkroom;146;146;146;145;145;145;145;145;146;145 19:35:32 INFO - PROCESS | 25270 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 19:35:32 INFO - PROCESS | 25270 | |8;json-parse-financial;53;53;52;53;53;52;54;54;52;52 19:35:32 INFO - PROCESS | 25270 | |9;json-stringify-tinderbox;61;61;60;62;61;61;60;61;61;64 19:35:32 INFO - PROCESS | 25270 | |10;stanford-crypto-aes;84;75;75;74;74;74;73;74;74;81 19:35:32 INFO - PROCESS | 25270 | |11;stanford-crypto-ccm;128;118;118;119;126;133;129;121;195;118 19:35:32 INFO - PROCESS | 25270 | |12;stanford-crypto-pbkdf2;156;155;156;155;159;154;155;154;153;157 19:35:32 INFO - PROCESS | 25270 | |13;stanford-crypto-sha256-iterative;62;63;62;61;64;63;62;61;60;61 19:35:32 INFO - PROCESS | 25270 | __end_tp_report 19:35:32 INFO - PROCESS | 25270 | __start_cc_report 19:35:32 INFO - PROCESS | 25270 | _x_x_mozilla_cycle_collect,1619 19:35:32 INFO - PROCESS | 25270 | __end_cc_report 19:35:32 INFO - PROCESS | 25270 | __startTimestamp1468290932112__endTimestamp 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | ------- Summary: start ------- 19:35:32 INFO - PROCESS | 25270 | Number of tests: 14 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.23 (2.9%) stddev-sans-first:1.12 19:35:32 INFO - PROCESS | 25270 | Values: 101.0 113.0 110.0 109.0 111.0 110.0 111.0 111.0 111.0 110.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:131.50 stddev:23.84 (18.1%) stddev-sans-first:25.07 19:35:32 INFO - PROCESS | 25270 | Values: 144.0 120.0 120.0 175.0 118.0 118.0 143.0 118.0 177.0 118.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 19:35:32 INFO - PROCESS | 25270 | Values: 161.0 163.0 159.0 159.0 159.0 160.0 160.0 160.0 160.0 160.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#3] audio-fft Cycles:10 Average:83.90 Median:75.00 stddev:19.67 (26.2%) stddev-sans-first:20.60 19:35:32 INFO - PROCESS | 25270 | Values: 75.0 75.0 75.0 132.0 76.0 75.0 75.0 74.0 107.0 75.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:83.00 stddev:17.27 (20.8%) stddev-sans-first:17.85 19:35:32 INFO - PROCESS | 25270 | Values: 83.0 83.0 112.0 84.0 84.0 83.0 120.0 83.0 84.0 124.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:35:32 INFO - PROCESS | 25270 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 19:35:32 INFO - PROCESS | 25270 | Values: 146.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 19:35:32 INFO - PROCESS | 25270 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 19:35:32 INFO - PROCESS | 25270 | Values: 53.0 53.0 52.0 53.0 53.0 52.0 54.0 54.0 52.0 52.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:1.14 (1.9%) stddev-sans-first:1.20 19:35:32 INFO - PROCESS | 25270 | Values: 61.0 61.0 60.0 62.0 61.0 61.0 60.0 61.0 61.0 64.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:74.50 stddev:3.65 (4.9%) stddev-sans-first:2.37 19:35:32 INFO - PROCESS | 25270 | Values: 84.0 75.0 75.0 74.0 74.0 74.0 73.0 74.0 74.0 81.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:127.00 stddev:23.30 (18.3%) stddev-sans-first:24.70 19:35:32 INFO - PROCESS | 25270 | Values: 128.0 118.0 118.0 119.0 126.0 133.0 129.0 121.0 195.0 118.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.40 Median:155.50 stddev:1.71 (1.1%) stddev-sans-first:1.80 19:35:32 INFO - PROCESS | 25270 | Values: 156.0 155.0 156.0 155.0 159.0 154.0 155.0 154.0 153.0 157.0 19:35:32 INFO - PROCESS | 25270 | 19:35:32 INFO - PROCESS | 25270 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.20 (1.9%) stddev-sans-first:1.27 19:35:32 INFO - PROCESS | 25270 | Values: 62.0 63.0 62.0 61.0 64.0 63.0 62.0 61.0 60.0 61.0 19:35:32 INFO - PROCESS | 25270 | -------- Summary: end -------- 19:35:32 INFO - PROCESS | 25270 | 19:35:33 INFO - TEST-INFO | 25270: exit 0 19:35:33 INFO - TEST-OK | kraken | took 60794ms 19:35:33 INFO - SUITE-END | took 1174s 19:35:33 INFO - Completed test suite (00:19:35) 19:35:33 INFO - javascript benchmark 19:35:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2957.7190166934142, 3055.2667359833617, 3088.961285551535, 3112.4242015227182, 3125.3280544682902, 5259.158534634459, 5285.524302812746, 5365.382980957191, 5455.945440545594, 5463.426340234273, 5320.920186197211, 5330.093884039713, 5382.865428364293, 5416.864578385543, 5427.9185812212845, 4795.568398844103, 4904.313396124546, 4913.926291105637, 4914.827981020665, 5112.130937740574, 4994.425641051289, 4999.625028122887, 5024.196128619422, 5050.3434553508, 5057.418396884369, 4862.4408193057725, 4870.586000189988, 4936.136176169156, 4940.8517744467545, 4948.1093403187415, 4933.432655244621, 4960.603151747851, 5013.072581572421, 5021.698698078127, 5047.9747601262125, 4762.476127625959, 4976.0545496355935, 5004.349434573519, 5016.924746128811, 5055.292259083732, 5273.31446911899, 5343.118385466393, 5361.919571206435, 5403.0, 5405.702686352249, 5744.396838331957, 5760.049591817345, 5771.278590176228, 5787.50806181475, 5788.0739081746715, 5577.749001294951, 5627.831165065014, 5804.216430781833, 5835.824925252249, 5846.824595262149, 5228.947710522884, 5335.6798592084215, 5338.039152952429, 5338.145896656555, 5345.38528069277, 5839.211706419579, 5841.824745257649, 5851.297844258716, 5853.502452291517, 5857.648541087548, 5449.155380915964, 5527.889442211133, 5581.972090139605, 5583.832485025456, 5584.525315348241, 5684.488396044376, 5720.027595308808, 5739.024365857814, 5740.167675687002, 5741.368449470555, 5595.580331475156, 5629.802956896571, 5636.04187288162, 5638.464345887134, 5638.633488823213, 5538.75075621598, 5674.631148975303, 5685.0, 5745.368009518949, 5761.567882408836, 4391.67062470316, 4392.912141757147, 4394.406755088086, 4412.227860124465, 4428.158649856581, 3917.3928041153667, 3925.685945124383, 3938.7045971552247, 3971.6028397160053, 3998.220347032359, 5287.576993840484, 5332.280142180756, 5341.5192632662465, 5372.301600791872, 5412.648177868426, 5712.171735098387, 5721.485066344049, 5729.4843464088435, 5746.569007324468, 5777.566682498829, 5418.864528386743, 5419.132938729785, 5423.240746295522, 5423.322084739408, 5424.1321388577635, 4888.144574699413, 4943.9258411123865, 4975.676581022222, 4993.0, 5004.574611158092, 5940.138679891392, 5951.553633477506, 5957.523398128139, 5958.136070269787, 5959.433853783884, 5619.634723742944, 5790.623609465371, 5806.2451881255165, 5809.738561764646, 5810.622309549865, 5914.2311499504785, 5944.5838791284195, 5948.940510594839, 5990.221271234712, 6011.819645410645, 5622.044252477007, 5648.830535083954, 5649.943500565106, 5655.660660360391, 5655.830325090254, 5588.888222235614, 5719.942800572107, 5762.078067509178, 5763.250777399079, 5768.538516918553, 5640.83077507659, 5695.772169113189, 5698.458646428583, 5746.310442746897, 5748.913766293593, 5603.10350343943, 5655.604107712421, 5656.5757568181725, 5656.85857853557, 5663.9716801415725, 5402.459754024723, 5607.0, 5629.4652008059165, 5639.238702775073, 5642.605017648725, 5695.686737229492, 5740.0815869460685, 5743.59794814359, 5778.971105144447, 5790.594658373873, 5578.414266501959, 5610.214569960127, 5624.04391253496, 5626.043572592751, 5628.549716022791], "value": 5305.527891022295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.3506143242433, 615.8368032471393, 615.987680246395, 629.0972454527756, 640.5260107520435, 34040.31919361611, 37425.25149497012, 43988.34017489736, 44807.551924480744, 45052.324215136796, 46852.297215541796, 47444.52555474444, 47500.28749568759, 47584.19326453884, 47692.284615730794, 8171.10117887032, 9030.638774449031, 9170.358074934757, 9174.128457796498, 9176.541172941359, 10568.15454763621, 10825.458727063655, 10828.133749300037, 11155.163362747786, 11156.721081972935, 11069.335839849635, 11166.050885674727, 11170.441477926071, 11178.496967636434, 11181.552737890475, 8259.091499935003, 10622.57509699611, 10917.23579349446, 10938.343699378023, 11008.39453830035, 10793.460326983619, 10858.891411085868, 10874.0, 10881.183911206612, 10882.72793180169, 43400.565994340286, 43740.781296093315, 43745.0, 43799.56200438018, 43809.12381752379, 2835.036087730161, 3532.982335088308, 3585.372559802024, 3706.5181526401393, 3888.4556162137324, 33893.322133557194, 33946.321073578634, 34449.827750861084, 34515.482267766005, 35236.0, 4408.008198155421, 4503.572160644733, 4553.840615578472, 4592.380028696117, 4743.312219728155, 377165.0, 377414.0, 378922.0, 379151.0, 379615.0, 34431.13922151966, 62129.37870621236, 62464.37535624586, 62561.37438625556, 62578.687106564175, 35898.82050589783, 36950.63049369472, 37550.24899502049, 37551.0, 37570.81214593964, 15521.37914483408, 15577.688446231012, 15704.842951570567, 15709.371625134867, 15710.214489275719, 11826.940865295619, 11842.940785296018, 11888.762224755457, 11893.108016898766, 11895.583654572049, 8459.0, 8497.36269779769, 8501.957490212633, 8560.657573696983, 8566.957165214259, 3075.7385622222364, 3194.632617249, 3230.305484320835, 3283.3433313337428, 3320.867165313409, 995.382862625177, 1044.5612842606124, 1064.3986147826454, 1165.4231155577875, 1172.8240763885315, 32685.673143268737, 33174.3365132696, 33330.0, 33366.666333336354, 33564.832175838965, 1144.5879483385975, 1159.5072094359864, 1215.3254943506438, 1216.2823933879054, 1217.72601164739, 17186.31254749796, 17906.5344450019, 18026.909865450853, 18059.27762889496, 18430.815691843178, 555570.0, 559176.0, 560833.0, 561094.0, 561503.0, 59444.10833837496, 61713.0, 61716.69141654263, 61755.6912215436, 61772.0, 1222.7371115210271, 1347.730453909222, 1368.9383977721027, 1370.205280937054, 1411.9929400352933, 32650.83674581707, 34357.0, 34641.67816011806, 34667.13332166716, 34712.65287347094, 52303.215451769014, 53132.46867531275, 53146.20280695715, 53159.73420132875, 53173.734131329096, 456781.0, 460331.0, 461009.0, 461622.0, 462233.0, 24885.0, 25834.61248081318, 28613.0, 28665.14004579866, 29563.26091847721, 48141.27788083111, 48164.518354816006, 48314.75842620905, 48868.2669759961, 49034.264486033455, 41670.79164604279, 42560.78719606382, 42594.57405425906, 42671.573284268, 42813.35779963365, 40956.0, 41346.379804302356, 41358.58641413668, 41508.377374340016, 41878.79060604677, 40845.79577102095, 41405.0, 41741.79129104335, 42197.15605687931, 42247.57752422559, 35500.64499355077, 37132.25735485221, 37559.81220093882, 37832.243355133294, 37980.0, 28150.29624259328, 28286.57961372276, 28696.0, 28727.856360718062, 28740.712592873802], "value": 19760.848510110827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.2729904275177, 401.161572313864, 403.93133167361543, 404.88865561970465, 416.8020190409554, 13315.933420332885, 18962.620747585057, 20764.480887977807, 20770.169193232272, 21715.0, 14768.926155369236, 19186.136623851926, 19207.807921920776, 20276.29032983846, 20520.38438846837, 5687.402822703619, 5856.707164641773, 5857.238558987326, 5859.824205273827, 5881.235439392873, 5706.942930570683, 5757.856053598652, 5772.509336706385, 5775.884482310351, 5781.508571771405, 4155.33514637658, 6120.081987701836, 6412.230532336126, 6425.261094974067, 6517.087607734921, 6187.443130118311, 6376.744930202786, 6468.32082631324, 6477.352264773555, 6500.707468163921, 5591.4688104629995, 5612.15817627355, 5620.100783874561, 5625.240592519998, 5626.465485778845, 23598.88200558986, 24337.756622433728, 24542.754572454225, 24646.383840404134, 25011.49977000468, 2468.987655061731, 2521.15541293665, 2523.8990440382363, 2538.6445897574354, 2544.643749875019, 13670.52153174634, 14682.265886705622, 14814.555563333117, 15057.096574205472, 15459.690806183813, 1949.4249196487071, 1986.384220891514, 2041.7039529268172, 2098.95802083959, 2106.452322396173, 83701.58149209215, 84150.15849841485, 85270.0, 87058.56470717606, 87739.0, 10245.463180522982, 10353.171746260281, 10916.399598022184, 11022.173336999756, 11132.380062274227, 15189.0, 15271.160086195367, 15302.158381289137, 15302.311395987208, 15305.540833775005, 8051.154628764012, 8503.787405314917, 8515.74452766418, 8570.442921210102, 8621.741347759576, 8241.670333186605, 8271.462354946909, 8329.625166867505, 8488.702895398632, 8489.660413583511, 6015.653894980961, 6276.717547710273, 6348.079528468346, 6670.833229169212, 6848.212455567574, 1812.5196822841958, 1822.7812662480321, 1845.7877344105332, 1859.414284500378, 1902.980970190308, 932.2122806228726, 988.6935050134481, 989.3569180033036, 997.9750506237402, 1079.4548752879903, 20132.584089909305, 20682.276120335715, 20724.378268651966, 20762.0, 20927.68608470874, 1031.0926384781344, 1039.6828967164936, 1083.8265877459569, 1086.462201210406, 1093.1801149138146, 11622.88377116235, 11915.640774955551, 11939.223950443193, 11954.76090478203, 12048.0, 473723.0, 475889.0, 476105.0, 482391.0, 500664.0, 85069.0, 86181.0, 86363.0, 87210.0, 87448.0, 981.2101258486844, 991.0436428846198, 996.9841230278321, 1019.2915923433143, 1029.5006921642996, 18170.27318907229, 18170.81829181691, 18515.166817493515, 18527.629447410705, 18897.43307700771, 39093.41359879547, 41941.0, 42789.14421711486, 42996.35505467358, 43195.56804431915, 49778.751106244235, 60713.69643151756, 61356.69321653363, 62110.0, 63316.050259245225, 7805.375569954504, 8465.111163327761, 8661.437006594802, 8809.691660792098, 8907.10928907104, 11969.581064662694, 13694.52069177574, 14306.356213970605, 14417.279136043366, 14461.0, 11734.119941004288, 11833.408329583659, 11966.162368634112, 12283.0, 12508.499660013498, 12009.0, 12051.337176455028, 12103.455344509694, 12163.706502048479, 12204.572839950559, 12487.812682809583, 12491.93754031224, 12537.247765134121, 12806.359682015676, 12894.161879477804, 8223.136570660234, 8247.257746802816, 8254.876176857231, 8314.916850831656, 8337.791555211168, 10770.676879693621, 11753.647390578297, 11853.051755859338, 12235.877641223475, 12526.624201273977], "value": 9615.145726682256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.3757253722995, 1482.5922871210416, 1505.2548988250817, 1643.2605327602575, 1812.9184186711589, 8729.869051964217, 9242.0, 9244.44533328, 9244.44533328, 9254.444733316006, 9007.054259302777, 9222.21611163052, 9240.630374785018, 9243.861342079874, 9251.44491330519, 2033.2273735980307, 2293.048384920256, 2360.350903501538, 2374.02664907388, 2403.230966090852, 2362.1850461590725, 2376.6197408414655, 2377.5720370333333, 2379.0840526397287, 2381.6546600742968, 2332.9533409331802, 2374.228375777879, 2394.4971555973266, 2399.9040038398443, 2400.4958958618713, 2030.8883011434366, 2203.5703037907615, 2253.5943530164564, 2277.225743247304, 2285.5771682238837, 2281.3270085324702, 2297.6094064009158, 2304.5390921815538, 2304.9078036878504, 2319.88400579972, 9298.349115561914, 9338.486383248919, 9357.859632105525, 9379.484128372937, 9416.482093484856, 5340.652857564246, 5493.093639549509, 5498.037843377393, 5499.50504454597, 5501.037318469252, 3515.6640476618722, 3518.6833185013215, 3564.1624218308675, 3569.7322700797545, 3574.7318951078773, 1182.3142577305143, 1185.146694380045, 1196.2822306616047, 1213.9028877689764, 1215.4591206912928, 13169.670758231025, 13203.207807531579, 13217.33913304331, 13244.602661920158, 13256.46974121034, 4702.694324868941, 4734.100520901018, 4770.964873497874, 4792.305115758196, 4941.505849415102, 10927.890721092846, 10975.067119294948, 10976.835347469794, 10988.395638239974, 10996.670099896855, 5357.919631205535, 5378.435264297193, 5389.137737961985, 5403.432639572867, 5405.0, 4392.868213953587, 4811.9518804811505, 4968.254761785761, 4970.478099799541, 5034.295198672189, 4071.776052317151, 4107.404426358161, 4185.183889141588, 4213.4522512073845, 4244.554321796229, 1112.2158877990996, 1206.1436380170178, 1271.7011502296953, 1313.9868601313865, 1327.6680829792551, 840.932725381968, 999.3254553176577, 999.9850002249972, 1015.827309357411, 1029.629333439961, 6028.703828676856, 6171.722272497748, 6195.256569211633, 6195.628262304276, 6273.309935907047, 985.8767654043245, 988.5057471264367, 994.9403035817874, 999.5102399824034, 1010.080866296404, 4181.435506206684, 4238.088810905609, 4275.1022285319805, 4292.871213863589, 4361.651067914623, 11034.944825275823, 11431.256968297033, 11527.423628818693, 11538.942305288589, 11550.075993920465, 11355.205135640426, 11372.886271137182, 11378.31730096197, 11395.0, 11401.372924489233, 8095.959520202361, 8384.0, 8419.494830310201, 8460.450070745383, 8547.914520854836, 6232.407921247474, 6414.069700819681, 6437.774677886251, 6553.967230163818, 6580.374864387875, 10340.53467593945, 10657.520411581652, 10712.464376781285, 10721.517531711248, 10753.085987691135, 10577.576896924036, 10805.837912431478, 10814.243002989715, 10954.56181752721, 11000.614978475714, 7861.292483676497, 8400.663973441238, 8428.536430496382, 8667.133286671282, 8716.651333946571, 8876.77808054778, 9067.637294508146, 9113.817723645623, 9135.862962055708, 9183.77040573991, 7828.473447677648, 7863.882041769492, 7988.960055199686, 8053.919460805551, 8208.097109318089, 7744.5740484271655, 7834.764957051298, 7839.294463498312, 7902.644381002984, 8162.551059691774, 7500.887486687595, 7536.020317358708, 7622.31399174077, 7623.885641715489, 7628.54228746277, 5445.401005889428, 5453.4819192178265, 5473.0, 5561.916571251353, 5626.396477004034, 5616.129499927518, 5705.686187259575, 5784.623999440191, 5828.94171058284, 5832.795852145155], "value": 4831.887916867355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.63396974314537, 339.13858798651427, 345.3817666377185, 348.27558677949867, 352.4554563199856, 5255.894882102356, 5363.89272214556, 5407.324084489439, 5415.241866138739, 5439.368189543136, 5185.792568297264, 5244.265802787603, 5257.0, 5296.496832800878, 5307.336582927134, 4189.9790501047455, 4196.999145610887, 4212.873613791591, 4237.173751118533, 4238.173556156551, 4163.916721665565, 4201.075763332062, 4205.285101532747, 4242.215190189825, 4254.212970600433, 4155.584441555835, 4180.327868852453, 4181.477315335583, 4182.707210495267, 4195.307774217251, 4159.854405095823, 4189.748615083075, 4195.475565554306, 4204.096119334357, 4213.641840443566, 3762.830672619739, 3766.773993560395, 3766.9623303766884, 3768.6419790119894, 3783.9783258520047, 4981.227909674007, 5238.214267859814, 5246.0032593807055, 5247.816326428557, 5269.868253293698, 4819.542143496362, 4825.758712064383, 4826.469088400273, 4828.348172996635, 4853.344798452199, 5341.732913354317, 5619.269494965628, 5621.690807005613, 5640.153976903456, 5697.430256974311, 4072.348424252135, 4074.5517993020744, 4080.5511393746665, 4084.305668036441, 4095.4471146395153, 5856.941430585724, 5885.411458854123, 5890.64515161511, 5902.4097590240635, 5916.455923952699, 3987.6809855212095, 4181.707280490397, 4186.623203911662, 4194.601512856274, 4196.9370459443135, 4186.099988502426, 4188.434561334242, 4191.350340697197, 4198.5591512891315, 4202.138561594866, 4716.85849424511, 4747.264174053027, 4759.785809638574, 4776.522347765195, 4803.663743537989, 5346.492083252085, 5540.168974653753, 5540.612157149041, 5557.277553918046, 5637.520810731134, 1368.7673095573775, 1408.6267139208119, 1436.2029073864105, 1441.8486058963658, 1442.9062110962755, 966.1449617088787, 974.4299584742863, 984.950752462374, 987.151050096916, 1000.3697670467559, 5341.919871201935, 5385.192221166856, 5392.703401312965, 5415.241866138744, 5434.375046869582, 5651.745671444713, 5670.177824215466, 5672.773089076473, 5682.232898558725, 5684.175794509855, 5179.870503237374, 5338.492843179892, 5349.946500534945, 5383.084875571162, 5437.809676661298, 2666.8133230673666, 2669.4927963686837, 2676.1168814291236, 2677.250369896432, 2692.0846912049997, 5184.248283998799, 5237.795307079316, 5249.816256431083, 5300.496452836975, 5300.602454815904, 5233.676615349281, 5476.26070480488, 5841.240638716939, 5854.302252346772, 5875.118732190205, 5905.940940590625, 5927.555433342516, 5948.917878742405, 5965.761369545169, 5976.342602313655, 5607.43925607436, 5797.565182611405, 5857.4435428634215, 5857.970710146422, 5885.411458854081, 5677.6657485491705, 5704.629199102045, 5709.857253568694, 5716.485516303553, 5725.599208055396, 5864.648121112747, 5926.621825559389, 5994.610350327215, 5995.760169593342, 6017.157597936205, 3941.3890846918775, 4122.896927576835, 4141.544430112745, 4155.542890282127, 4193.748375097504, 5582.748776305158, 5732.656040637575, 5788.7395067222915, 5801.071828507418, 5820.679862607597, 5514.420985796433, 5593.272874526286, 5601.271834661468, 5610.961972035187, 5636.267285253055, 5610.382857885547, 5618.7190640467, 5627.521660658726, 5642.774289028393, 5661.235733175969, 5429.972850135724, 5597.384287728428, 5673.092305231144, 5673.092305231144, 5690.118031705092, 5681.744321505625, 5710.400407957105, 5711.628744131618, 5715.114157305624, 5731.942680573141, 5483.561315094863, 5603.859903502445, 5652.378238393856, 5661.801836935688, 5675.177099320658], "value": 4271.517024008939, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.1717074340287, 829.4981536170617, 840.7351684219474, 866.2377108144833, 874.169538938009, 15182.468613598512, 15840.445584404548, 15871.920640396782, 15872.285747141392, 15875.761863572056, 13916.304184790746, 13951.441942322295, 13956.0, 13968.930155349235, 13969.930150349233, 801.1827935505795, 822.8066404394963, 824.9958750206241, 825.9669613215494, 826.1531929771971, 663.4493370502496, 791.9643616037264, 803.2971150243537, 804.2761514636844, 805.9274665280124, 870.3428911172056, 873.8164985353084, 876.4609764994515, 876.7238319929202, 876.9298456123495, 864.464032299978, 866.1511718515827, 866.1728049712541, 866.2204016385241, 866.7876370289288, 509.5923261390872, 608.6348191085358, 628.5662892604089, 629.8866204083263, 632.1623848400841, 15249.08505489663, 15351.923240383727, 15375.154366509838, 15386.769198462032, 15407.922960385238, 14262.144271343752, 14331.713365732627, 14409.847212223101, 14827.77758333626, 15068.924655376653, 13096.345182740824, 13365.0, 13412.12821166621, 13623.659408514866, 14116.223607701573, 4552.362669226311, 4565.6347492200875, 4572.839950601746, 4573.862784116482, 4765.26138448541, 73282.0, 74410.25589744087, 74505.62747186229, 74645.25354746437, 75387.6230618849, 11134.27627204229, 11930.821037684442, 12037.87962120385, 12135.87864121365, 12449.626511204679, 20024.0, 21303.573928521342, 21329.25347612857, 21332.893335533532, 21336.466588335104, 8219.219174178443, 8821.0, 8834.55827208637, 8842.381033327605, 8872.600732967032, 5052.5705315048635, 5108.514691104339, 5135.97432012845, 5138.87152821175, 5141.742912854343, 3473.6179020307745, 3619.638036196412, 3645.872394466181, 3647.325244829715, 3652.6712595866497, 642.4773287309107, 644.0725355488175, 649.7368565730886, 659.6437923521245, 667.2926697700356, 566.940471250521, 668.9899651505232, 703.8345988692653, 763.522798251093, 765.7243392378739, 9178.638226749938, 9462.432254064779, 9553.140217380469, 9616.423014619146, 9687.17658998979, 706.5372181221309, 709.1490211745926, 724.1165777751136, 726.2338233164063, 748.0686545251089, 4598.965232822588, 4762.472787999856, 4873.074115917964, 4925.11347957364, 5222.321098257202, 111930.44034779775, 130805.34597326952, 132781.3360933189, 133550.0, 137419.31290343683, 33604.64767466254, 33691.326173476395, 62543.0, 82834.17165828386, 107540.46229768958, 69267.65366173137, 74529.0, 99542.5022874881, 100917.49541252246, 102346.0, 10646.574137034431, 10694.899018120821, 10877.0, 10916.0, 11004.889951100387, 40536.94630536923, 53988.19017714816, 54450.727746361015, 55161.72419137879, 56992.30076992433, 84073.57963210145, 88515.0, 90107.54946225227, 90579.0, 90673.54663226905, 12446.298764685856, 12598.181118227287, 12712.936435317764, 12730.68173295675, 12963.15739476931, 16347.346106156096, 16351.427700030441, 16353.0, 16841.494755156873, 17033.23350449208, 13136.737265254833, 13420.46318147263, 13506.0, 13875.236861972688, 14488.348024338718, 14593.781093283822, 14599.781003284746, 15437.691246174789, 15555.45555905538, 15738.770803094088, 14232.0, 14366.209858457885, 14651.706965860836, 14718.411263549338, 15159.317830697424, 11919.642410727693, 11998.220115692453, 12120.878791211975, 12310.446029928853, 12505.56230531927, 11141.859228823856, 11545.769084618092, 11805.527778888749, 12296.262224266573, 12310.199837010565], "value": 7778.637236316584, "unit": "score"}], "name": "dromaeo_css", "value": 7382.259860800454, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 110.0, 109.0, 111.0, 110.0, 111.0, 111.0, 111.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 120.0, 175.0, 118.0, 118.0, 143.0, 118.0, 177.0, 118.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 159.0, 159.0, 159.0, 160.0, 160.0, 160.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 132.0, 76.0, 75.0, 75.0, 74.0, 107.0, 75.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 112.0, 84.0, 84.0, 83.0, 120.0, 83.0, 84.0, 124.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 53.0, 53.0, 52.0, 54.0, 54.0, 52.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 62.0, 61.0, 61.0, 60.0, 61.0, 61.0, 64.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 75.0, 74.0, 74.0, 74.0, 73.0, 74.0, 74.0, 81.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 118.0, 118.0, 119.0, 126.0, 133.0, 129.0, 121.0, 195.0, 118.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 155.0, 156.0, 155.0, 159.0, 154.0, 155.0, 154.0, 153.0, 157.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 61.0, 64.0, 63.0, 62.0, 61.0, 60.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1477.5, "alertThreshold": 2.0}]} 19:35:33 INFO - Return code: 0 19:35:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:35:33 INFO - # TBPL SUCCESS # 19:35:33 INFO - Running post-action listener: _resource_record_post_action 19:35:33 INFO - ##### 19:35:33 INFO - ##### Finished run-tests step (success) 19:35:33 INFO - ##### 19:35:33 INFO - Running post-run listener: _resource_record_post_run 19:35:33 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 570241024; Read time: 256; Write time: 572816 19:35:33 INFO - TinderboxPrint: CPU usage
12.9% 19:35:33 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 256 19:35:33 INFO - TinderboxPrint: I/O write bytes / time
570,241,024 / 572,816 19:35:33 INFO - TinderboxPrint: CPU idle
8,281.2 (87.0%) 19:35:33 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 19:35:33 INFO - TinderboxPrint: Swap in / out
0 / 0 19:35:33 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 273354752; Read time: 0; Write time: 285128 19:35:33 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296886272; Read time: 248; Write time: 287688 19:35:34 INFO - Running post-run listener: _upload_blobber_files 19:35:34 INFO - Blob upload gear active. 19:35:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:35:34 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 19:35:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:35:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:35:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:35:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:35 INFO - (blobuploader) - INFO - Done attempting. 19:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:35:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:38 INFO - (blobuploader) - INFO - Done attempting. 19:35:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:35:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:35:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:39 INFO - (blobuploader) - INFO - Done attempting. 19:35:39 INFO - (blobuploader) - INFO - Iteration through files over. 19:35:39 INFO - Return code: 0 19:35:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:35:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:35:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c82259fba8045fddd2d3783661094ae7d83f4457be6f8c642e9ad1289a72fa42a60e6368b31061a4d05090ced18e847632df532dbd410dff541f7a768596510d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8149d4e95503ad52ac434ec5036b5ad519caa4e1008692df1742a7d4987a87c940f4242faf125a34445dba59728ecc15802ae8d97fa98ccdc987d9cde3bf2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4e1077eb734274bf74224182f37a4703828e5fd5e4c5ca149daf0962cf1dd2d95b26c480343eb73f65cb24f6b46bda9fbb6ce8333ea2af1af81c0dfecdc2749"} 19:35:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:35:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:35:39 INFO - Contents: 19:35:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c82259fba8045fddd2d3783661094ae7d83f4457be6f8c642e9ad1289a72fa42a60e6368b31061a4d05090ced18e847632df532dbd410dff541f7a768596510d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8149d4e95503ad52ac434ec5036b5ad519caa4e1008692df1742a7d4987a87c940f4242faf125a34445dba59728ecc15802ae8d97fa98ccdc987d9cde3bf2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4e1077eb734274bf74224182f37a4703828e5fd5e4c5ca149daf0962cf1dd2d95b26c480343eb73f65cb24f6b46bda9fbb6ce8333ea2af1af81c0dfecdc2749"} 19:35:39 INFO - Running post-run listener: copy_logs_to_upload_dir 19:35:39 INFO - Copying logs to upload dir... 19:35:39 INFO - mkdir: /builds/slave/test/build/upload/logs 19:35:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.890223 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-11 19:35:39.322919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 19:35:39.326982) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c82259fba8045fddd2d3783661094ae7d83f4457be6f8c642e9ad1289a72fa42a60e6368b31061a4d05090ced18e847632df532dbd410dff541f7a768596510d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8149d4e95503ad52ac434ec5036b5ad519caa4e1008692df1742a7d4987a87c940f4242faf125a34445dba59728ecc15802ae8d97fa98ccdc987d9cde3bf2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4e1077eb734274bf74224182f37a4703828e5fd5e4c5ca149daf0962cf1dd2d95b26c480343eb73f65cb24f6b46bda9fbb6ce8333ea2af1af81c0dfecdc2749"} build_url:https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/DbfvJgsmQ7uJOrT6n1M03Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c82259fba8045fddd2d3783661094ae7d83f4457be6f8c642e9ad1289a72fa42a60e6368b31061a4d05090ced18e847632df532dbd410dff541f7a768596510d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8149d4e95503ad52ac434ec5036b5ad519caa4e1008692df1742a7d4987a87c940f4242faf125a34445dba59728ecc15802ae8d97fa98ccdc987d9cde3bf2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4e1077eb734274bf74224182f37a4703828e5fd5e4c5ca149daf0962cf1dd2d95b26c480343eb73f65cb24f6b46bda9fbb6ce8333ea2af1af81c0dfecdc2749"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 19:35:39.352814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:35:39.353139) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1468288864.213272-1941319898 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 19:35:39.438421) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 19:35:39.438745) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 19:35:39.439034) ========= ========= Total master_lag: 0.21 =========