builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1468059318.64 results: success (0) buildid: 20160709003001 builduid: a1824a7c5b154dd284533ec0b0edabdc revision: e3dcf062007e280ccf06e7bed7ff904d0fb44418 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.640472) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.640860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.641183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.739956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.740217) ========= 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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-09 03:15:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-07-09 03:15:19 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107228 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.864172) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.864495) ========= 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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017694 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:18.917852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-09 03:15:18.918180) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e3dcf062007e280ccf06e7bed7ff904d0fb44418 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e3dcf062007e280ccf06e7bed7ff904d0fb44418 --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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-09 03:15:19,630 truncating revision to first 12 chars 2016-07-09 03:15:19,630 Setting DEBUG logging. 2016-07-09 03:15:19,630 attempt 1/10 2016-07-09 03:15:19,630 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e3dcf062007e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-09 03:15:20,356 unpacking tar archive at: autoland-e3dcf062007e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.164803 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-09 03:15:20.097722) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:20.098036) ========= script_repo_revision: e3dcf062007e280ccf06e7bed7ff904d0fb44418 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:20.098465) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:20.098770) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 03:15:20.109403) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-09 03:15:20.109741) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:15:20 INFO - MultiFileLogger online at 20160709 03:15:20 in /builds/slave/test 03:15:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:15:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:20 INFO - {'append_to_log': False, 03:15:20 INFO - 'base_work_dir': '/builds/slave/test', 03:15:20 INFO - 'blob_upload_branch': 'Autoland', 03:15:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:20 INFO - 'branch': 'Autoland', 03:15:20 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:20 INFO - 'config_files': ('talos/linux_config.py',), 03:15:20 INFO - 'default_actions': ('clobber', 03:15:20 INFO - 'read-buildbot-config', 03:15:20 INFO - 'download-and-extract', 03:15:20 INFO - 'populate-webroot', 03:15:20 INFO - 'create-virtualenv', 03:15:20 INFO - 'install', 03:15:20 INFO - 'run-tests'), 03:15:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:20 INFO - 'download_minidump_stackwalk': True, 03:15:20 INFO - 'download_symbols': 'ondemand', 03:15:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:15:20 INFO - 'tooltool.py': '/tools/tooltool.py', 03:15:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:15:20 INFO - '/tools/misc-python/virtualenv.py')}, 03:15:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:20 INFO - 'installer_path': 'installer.exe', 03:15:20 INFO - 'log_level': 'info', 03:15:20 INFO - 'log_name': 'talos', 03:15:20 INFO - 'log_to_console': True, 03:15:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:15:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:15:20 INFO - 'opt_config_files': (), 03:15:20 INFO - 'pip_index': False, 03:15:20 INFO - 'sps_profile': False, 03:15:20 INFO - 'sps_profile_interval': 0, 03:15:20 INFO - 'suite': 'dromaeojs', 03:15:20 INFO - 'system_bits': '32', 03:15:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:20 INFO - 'title': 'talos-linux64-ix-065', 03:15:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:20 INFO - 'use_talos_json': True, 03:15:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:20 INFO - 'work_dir': 'build'} 03:15:20 INFO - ##### 03:15:20 INFO - ##### Running clobber step. 03:15:20 INFO - ##### 03:15:20 INFO - Running pre-action listener: _resource_record_pre_action 03:15:20 INFO - Running main action method: clobber 03:15:20 INFO - rmtree: /builds/slave/test/build 03:15:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:21 INFO - Running post-action listener: _resource_record_post_action 03:15:21 INFO - ##### 03:15:21 INFO - ##### Finished clobber step (success) 03:15:21 INFO - ##### 03:15:21 INFO - ##### 03:15:21 INFO - ##### Running read-buildbot-config step. 03:15:21 INFO - ##### 03:15:21 INFO - Running pre-action listener: _resource_record_pre_action 03:15:21 INFO - Running main action method: read_buildbot_config 03:15:21 INFO - Using buildbot properties: 03:15:21 INFO - { 03:15:21 INFO - "project": "", 03:15:21 INFO - "product": "firefox", 03:15:21 INFO - "script_repo_revision": "production", 03:15:21 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 03:15:21 INFO - "repository": "", 03:15:21 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 03:15:21 INFO - "buildid": "20160709003001", 03:15:21 INFO - "pgo_build": "True", 03:15:21 INFO - "basedir": "/builds/slave/test", 03:15:21 INFO - "buildnumber": 28, 03:15:21 INFO - "slavename": "talos-linux64-ix-065", 03:15:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:15:21 INFO - "platform": "ubuntu64_hw", 03:15:21 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 03:15:21 INFO - "branch": "autoland", 03:15:21 INFO - "repo_path": "integration/autoland", 03:15:21 INFO - "revision": "e3dcf062007e280ccf06e7bed7ff904d0fb44418", 03:15:21 INFO - "stage_platform": "linux64-pgo", 03:15:21 INFO - "builduid": "a1824a7c5b154dd284533ec0b0edabdc", 03:15:21 INFO - "slavebuilddir": "test" 03:15:21 INFO - } 03:15:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 03:15:21 INFO - Running post-action listener: _resource_record_post_action 03:15:21 INFO - ##### 03:15:21 INFO - ##### Finished read-buildbot-config step (success) 03:15:21 INFO - ##### 03:15:21 INFO - ##### 03:15:21 INFO - ##### Running download-and-extract step. 03:15:21 INFO - ##### 03:15:21 INFO - Running pre-action listener: _resource_record_pre_action 03:15:21 INFO - Running main action method: download_and_extract 03:15:21 INFO - mkdir: /builds/slave/test/build/tests 03: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')]} 03:15:21 INFO - https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:15:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:15:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:15:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:28 INFO - Downloaded 1361 bytes. 03:15:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:15:28 INFO - Using the following test package requirements: 03:15:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:15:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:15:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:15:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'jsshell-linux-x86_64.zip'], 03:15:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:15:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:15:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:15:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:15:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:15:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:15:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:15:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:15:28 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')]} 03:15:28 INFO - https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:15:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:15:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:15:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:32 INFO - Downloaded 24303043 bytes. 03:15:32 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'] 03:15:32 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 03:15:33 INFO - Return code: 0 03:15:33 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 03: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')]} 03:15:33 INFO - https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:15:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:15:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:15:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:35 INFO - Downloaded 24303043 bytes. 03:15:35 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'] 03:15:35 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 03:15:36 INFO - Return code: 0 03: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')]} 03:15:36 INFO - https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:15:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:15:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:15:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:37 INFO - Downloaded 14403333 bytes. 03:15:37 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'] 03:15:37 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 03:15:38 INFO - Return code: 0 03:15:38 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')]} 03:15:38 INFO - https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:15:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:15:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:15:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IbHkw6BCSwOKmP8VM1dq8g/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 03:15:39 INFO - Downloaded 60420567 bytes. 03:15:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:15:39 INFO - mkdir: /builds/slave/test/properties 03:15:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:39 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:39 INFO - Contents: 03:15:39 INFO - build_url:https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:15:40 INFO - Running post-action listener: _resource_record_post_action 03:15:40 INFO - Running post-action listener: set_extra_try_arguments 03:15:40 INFO - ##### 03:15:40 INFO - ##### Finished download-and-extract step (success) 03:15:40 INFO - ##### 03:15:40 INFO - ##### 03:15:40 INFO - ##### Running populate-webroot step. 03:15:40 INFO - ##### 03:15:40 INFO - Running pre-action listener: _resource_record_pre_action 03:15:40 INFO - Running main action method: populate_webroot 03:15:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:15:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:15:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:15:40 INFO - '--tppagecycles', 03:15:40 INFO - '7'], 03:15:40 INFO - 'tests': ['tsvgm']}}, 03:15:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:15:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:15:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['damp', 'tps']}, 03:15:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'tests': ['damp', 'tps']}, 03:15:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['dromaeo_dom']}, 03:15:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['basic_compositor_video']}, 03:15:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:15:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['a11yr', 03:15:40 INFO - 'ts_paint', 03:15:40 INFO - 'tpaint', 03:15:40 INFO - 'sessionrestore', 03:15:40 INFO - 'sessionrestore_no_auto_restore', 03:15:40 INFO - 'tabpaint']}, 03:15:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['tsvgx', 03:15:40 INFO - 'tsvgr_opacity', 03:15:40 INFO - 'tart', 03:15:40 INFO - 'tscrollx', 03:15:40 INFO - 'cart']}, 03:15:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:40 INFO - 'tsvgr_opacity', 03:15:40 INFO - 'tart', 03:15:40 INFO - 'tscrollx', 03:15:40 INFO - 'cart']}, 03:15:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'talos_options': ['--disable-e10s'], 03:15:40 INFO - 'tests': ['tp5o']}, 03:15:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'tests': ['tp5o']}, 03:15:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'talos_options': ['--disable-e10s', 03:15:40 INFO - '--xperf_path', 03:15:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:40 INFO - 'tests': ['tp5n']}, 03:15:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:40 INFO - 'talos_options': ['--xperf_path', 03:15:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:40 INFO - 'tests': ['tp5n']}}, 03:15:40 INFO - 'talos.zip': {'path': '', 03:15:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:15:40 INFO - Running post-action listener: _resource_record_post_action 03:15:40 INFO - ##### 03:15:40 INFO - ##### Finished populate-webroot step (success) 03:15:40 INFO - ##### 03:15:40 INFO - ##### 03:15:40 INFO - ##### Running create-virtualenv step. 03:15:40 INFO - ##### 03:15:40 INFO - Running pre-action listener: _resource_record_pre_action 03:15:40 INFO - Running main action method: create_virtualenv 03:15:40 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:40 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 03:15:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:15:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:40 INFO - Using real prefix '/usr' 03:15:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:40 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:42 INFO - Installing pip.................done. 03:15:42 INFO - Return code: 0 03:15:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:42 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:42 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 03:15:42 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 03:15:42 INFO - Using env: {'DISPLAY': ':0', 03:15:42 INFO - 'HOME': '/home/cltbld', 03:15:42 INFO - 'LANG': 'en_US.UTF-8', 03:15:42 INFO - 'LANGUAGE': 'en_US:en', 03:15:42 INFO - 'LOGNAME': 'cltbld', 03:15:42 INFO - 'MAIL': '/var/mail/cltbld', 03:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:42 INFO - 'MOZ_NO_REMOTE': '1', 03:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:42 INFO - 'NO_EM_RESTART': '1', 03:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:42 INFO - 'PWD': '/builds/slave/test', 03:15:42 INFO - 'SHELL': '/bin/bash', 03:15:42 INFO - 'SHLVL': '1', 03:15:42 INFO - 'TERM': 'linux', 03:15:42 INFO - 'TMOUT': '86400', 03:15:42 INFO - 'USER': 'cltbld', 03:15:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:42 INFO - '_': '/tools/buildbot/bin/python'} 03:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:43 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 03:15:43 INFO - Cleaning up... 03:15:43 INFO - Return code: 0 03:15:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:43 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:43 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 03:15:43 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 03:15:43 INFO - Using env: {'DISPLAY': ':0', 03:15:43 INFO - 'HOME': '/home/cltbld', 03:15:43 INFO - 'LANG': 'en_US.UTF-8', 03:15:43 INFO - 'LANGUAGE': 'en_US:en', 03:15:43 INFO - 'LOGNAME': 'cltbld', 03:15:43 INFO - 'MAIL': '/var/mail/cltbld', 03:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:43 INFO - 'MOZ_NO_REMOTE': '1', 03:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:43 INFO - 'NO_EM_RESTART': '1', 03:15:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:43 INFO - 'PWD': '/builds/slave/test', 03:15:43 INFO - 'SHELL': '/bin/bash', 03:15:43 INFO - 'SHLVL': '1', 03:15:43 INFO - 'TERM': 'linux', 03:15:43 INFO - 'TMOUT': '86400', 03:15:43 INFO - 'USER': 'cltbld', 03:15:43 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:43 INFO - '_': '/tools/buildbot/bin/python'} 03:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:43 INFO - Downloading/unpacking psutil>=3.1.1 03:15:43 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 03:15:43 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 03:15:43 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 03:15:43 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 03:15:43 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 03:15:43 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 03:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:15:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:45 INFO - Installing collected packages: psutil 03:15:45 INFO - Running setup.py install for psutil 03:15:45 INFO - building 'psutil._psutil_linux' extension 03:15:45 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 03:15:45 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 03:15:45 INFO - building 'psutil._psutil_posix' extension 03:15:45 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 03:15:45 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 03:15:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:45 INFO - Successfully installed psutil 03:15:45 INFO - Cleaning up... 03:15:45 INFO - Return code: 0 03:15:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:15:45 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')]} 03:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:45 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')]} 03:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:45 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:45 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 03:15:45 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 03:15:45 INFO - Using env: {'DISPLAY': ':0', 03:15:45 INFO - 'HOME': '/home/cltbld', 03:15:45 INFO - 'LANG': 'en_US.UTF-8', 03:15:45 INFO - 'LANGUAGE': 'en_US:en', 03:15:45 INFO - 'LOGNAME': 'cltbld', 03:15:45 INFO - 'MAIL': '/var/mail/cltbld', 03:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:45 INFO - 'MOZ_NO_REMOTE': '1', 03:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:45 INFO - 'NO_EM_RESTART': '1', 03:15:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:45 INFO - 'PWD': '/builds/slave/test', 03:15:45 INFO - 'SHELL': '/bin/bash', 03:15:45 INFO - 'SHLVL': '1', 03:15:45 INFO - 'TERM': 'linux', 03:15:45 INFO - 'TMOUT': '86400', 03:15:45 INFO - 'USER': 'cltbld', 03:15:45 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:45 INFO - '_': '/tools/buildbot/bin/python'} 03:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:15:46 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 03:15:46 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 03:15:46 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 03:15:46 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 03:15:46 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 03:15:46 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 03:15:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:15:48 INFO - Installing collected packages: mozsystemmonitor 03:15:48 INFO - Running setup.py install for mozsystemmonitor 03:15:48 INFO - Successfully installed mozsystemmonitor 03:15:48 INFO - Cleaning up... 03:15:48 INFO - Return code: 0 03:15:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:15:48 INFO - Using env: {'DISPLAY': ':0', 03:15:48 INFO - 'HOME': '/home/cltbld', 03:15:48 INFO - 'LANG': 'en_US.UTF-8', 03:15:48 INFO - 'LANGUAGE': 'en_US:en', 03:15:48 INFO - 'LOGNAME': 'cltbld', 03:15:48 INFO - 'MAIL': '/var/mail/cltbld', 03:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:48 INFO - 'MOZ_NO_REMOTE': '1', 03:15:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:48 INFO - 'NO_EM_RESTART': '1', 03:15:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:48 INFO - 'PWD': '/builds/slave/test', 03:15:48 INFO - 'SHELL': '/bin/bash', 03:15:48 INFO - 'SHLVL': '1', 03:15:48 INFO - 'TERM': 'linux', 03:15:48 INFO - 'TMOUT': '86400', 03:15:48 INFO - 'USER': 'cltbld', 03:15:48 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:48 INFO - '_': '/tools/buildbot/bin/python'} 03:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:48 INFO - Downloading/unpacking jsonschema==2.5.1 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:15:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 INFO - Downloading functools32-3.2.3-2.tar.gz 03:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:15:50 INFO - warning: no files found matching '*.txt' 03:15:50 INFO - no previously-included directories found matching 'build' 03:15:50 INFO - no previously-included directories found matching 'dist' 03:15:50 INFO - no previously-included directories found matching '.git*' 03:15:50 INFO - Installing collected packages: jsonschema, functools32 03:15:50 INFO - Running setup.py install for functools32 03:15:50 INFO - warning: no files found matching '*.txt' 03:15:50 INFO - no previously-included directories found matching 'build' 03:15:50 INFO - no previously-included directories found matching 'dist' 03:15:50 INFO - no previously-included directories found matching '.git*' 03:15:50 INFO - Successfully installed jsonschema functools32 03:15:50 INFO - Cleaning up... 03:15:50 INFO - Return code: 0 03:15:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:15:50 INFO - Using env: {'DISPLAY': ':0', 03:15:50 INFO - 'HOME': '/home/cltbld', 03:15:50 INFO - 'LANG': 'en_US.UTF-8', 03:15:50 INFO - 'LANGUAGE': 'en_US:en', 03:15:50 INFO - 'LOGNAME': 'cltbld', 03:15:50 INFO - 'MAIL': '/var/mail/cltbld', 03:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:50 INFO - 'MOZ_NO_REMOTE': '1', 03:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:50 INFO - 'NO_EM_RESTART': '1', 03:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:50 INFO - 'PWD': '/builds/slave/test', 03:15:50 INFO - 'SHELL': '/bin/bash', 03:15:50 INFO - 'SHLVL': '1', 03:15:50 INFO - 'TERM': 'linux', 03:15:50 INFO - 'TMOUT': '86400', 03:15:50 INFO - 'USER': 'cltbld', 03:15:50 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:50 INFO - '_': '/tools/buildbot/bin/python'} 03:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:50 INFO - Downloading/unpacking blobuploader==1.2.4 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:15:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:15:52 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 03:15:52 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 03:15:52 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 03:15:52 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 03:15:52 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 03:15:52 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 03:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:15:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:53 INFO - Downloading docopt-0.6.1.tar.gz 03:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:15:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:15:53 INFO - Running setup.py install for blobuploader 03:15:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:15:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:15:53 INFO - Running setup.py install for requests 03:15:54 INFO - Running setup.py install for docopt 03:15:54 INFO - Successfully installed blobuploader requests docopt 03:15:54 INFO - Cleaning up... 03:15:54 INFO - Return code: 0 03:15:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:54 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:54 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 03:15:54 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 03:15:54 INFO - Using env: {'DISPLAY': ':0', 03:15:54 INFO - 'HOME': '/home/cltbld', 03:15:54 INFO - 'LANG': 'en_US.UTF-8', 03:15:54 INFO - 'LANGUAGE': 'en_US:en', 03:15:54 INFO - 'LOGNAME': 'cltbld', 03:15:54 INFO - 'MAIL': '/var/mail/cltbld', 03:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:54 INFO - 'MOZ_NO_REMOTE': '1', 03:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:54 INFO - 'NO_EM_RESTART': '1', 03:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:54 INFO - 'PWD': '/builds/slave/test', 03:15:54 INFO - 'SHELL': '/bin/bash', 03:15:54 INFO - 'SHLVL': '1', 03:15:54 INFO - 'TERM': 'linux', 03:15:54 INFO - 'TMOUT': '86400', 03:15:54 INFO - 'USER': 'cltbld', 03:15:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:54 INFO - '_': '/tools/buildbot/bin/python'} 03:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 INFO - Running setup.py (path:/tmp/pip-i3A1_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 INFO - Running setup.py (path:/tmp/pip-5yqDXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:54 INFO - Running setup.py (path:/tmp/pip-nuZAeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:54 INFO - Running setup.py (path:/tmp/pip-9tQAhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:54 INFO - Running setup.py (path:/tmp/pip-O_IO1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:54 INFO - Running setup.py (path:/tmp/pip-DnSBIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:54 INFO - Running setup.py (path:/tmp/pip-SQqxVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:54 INFO - Running setup.py (path:/tmp/pip-fqNV5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:55 INFO - Running setup.py (path:/tmp/pip-gIqQeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:55 INFO - Running setup.py (path:/tmp/pip-Odn7Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 INFO - Running setup.py (path:/tmp/pip-vdwuTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:55 INFO - Running setup.py (path:/tmp/pip-Xqif8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:55 INFO - Running setup.py (path:/tmp/pip-T2ijDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:55 INFO - Running setup.py (path:/tmp/pip-yZqBtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:55 INFO - Running setup.py (path:/tmp/pip-tBaqfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:55 INFO - Running setup.py (path:/tmp/pip-QPvjK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:55 INFO - Running setup.py (path:/tmp/pip-FJm4J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:15:55 INFO - Running setup.py install for manifestparser 03:15:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:15:55 INFO - Running setup.py install for mozcrash 03:15:55 INFO - Running setup.py install for mozdebug 03:15:55 INFO - Running setup.py install for mozdevice 03:15:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for mozfile 03:15:56 INFO - Running setup.py install for mozhttpd 03:15:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for mozinfo 03:15:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for mozInstall 03:15:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for mozleak 03:15:56 INFO - Running setup.py install for mozlog 03:15:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for moznetwork 03:15:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:15:56 INFO - Running setup.py install for mozprocess 03:15:56 INFO - Running setup.py install for mozprofile 03:15:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:15:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:15:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:15:57 INFO - Running setup.py install for mozrunner 03:15:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:15:57 INFO - Running setup.py install for mozscreenshot 03:15:57 INFO - Running setup.py install for moztest 03:15:57 INFO - Running setup.py install for mozversion 03:15:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:15:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:15:57 INFO - Cleaning up... 03:15:57 INFO - Return code: 0 03:15:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03: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')]} 03:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03: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')]} 03:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:57 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:15:57 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 03:15:57 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 03:15:57 INFO - Using env: {'DISPLAY': ':0', 03:15:57 INFO - 'HOME': '/home/cltbld', 03:15:57 INFO - 'LANG': 'en_US.UTF-8', 03:15:57 INFO - 'LANGUAGE': 'en_US:en', 03:15:57 INFO - 'LOGNAME': 'cltbld', 03:15:57 INFO - 'MAIL': '/var/mail/cltbld', 03:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:57 INFO - 'MOZ_NO_REMOTE': '1', 03:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:57 INFO - 'NO_EM_RESTART': '1', 03:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:57 INFO - 'PWD': '/builds/slave/test', 03:15:57 INFO - 'SHELL': '/bin/bash', 03:15:57 INFO - 'SHLVL': '1', 03:15:57 INFO - 'TERM': 'linux', 03:15:57 INFO - 'TMOUT': '86400', 03:15:57 INFO - 'USER': 'cltbld', 03:15:57 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:57 INFO - '_': '/tools/buildbot/bin/python'} 03:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:57 INFO - Running setup.py (path:/tmp/pip-GhInoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:57 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)) 03:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:57 INFO - Running setup.py (path:/tmp/pip-qyLFJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:57 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)) 03:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:57 INFO - Running setup.py (path:/tmp/pip-UCnzg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:57 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)) 03:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:57 INFO - Running setup.py (path:/tmp/pip-JaL_VC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:58 INFO - Running setup.py (path:/tmp/pip-nnj8v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:58 INFO - Running setup.py (path:/tmp/pip-AICt7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:58 INFO - Running setup.py (path:/tmp/pip-esrGpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:58 INFO - Running setup.py (path:/tmp/pip-4P3cGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:58 INFO - Running setup.py (path:/tmp/pip-0MpZSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:58 INFO - Running setup.py (path:/tmp/pip-sc4KoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:58 INFO - Running setup.py (path:/tmp/pip-jnTgYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:58 INFO - Running setup.py (path:/tmp/pip-P91hx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:58 INFO - Running setup.py (path:/tmp/pip-odDf0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:58 INFO - Running setup.py (path:/tmp/pip-tik8zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:58 INFO - Running setup.py (path:/tmp/pip-wtSz0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:58 INFO - Running setup.py (path:/tmp/pip-URfkni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:58 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)) 03:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:58 INFO - Running setup.py (path:/tmp/pip-X2MiUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:00 INFO - Downloading blessings-1.6.tar.gz 03:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:16:00 INFO - Installing collected packages: blessings 03:16:00 INFO - Running setup.py install for blessings 03:16:01 INFO - Successfully installed blessings 03:16:01 INFO - Cleaning up... 03:16:01 INFO - Return code: 0 03:16:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:01 INFO - Reading from file tmpfile_stdout 03:16:01 INFO - Current package versions: 03:16:01 INFO - argparse == 1.2.1 03:16:01 INFO - blessings == 1.6 03:16:01 INFO - blobuploader == 1.2.4 03:16:01 INFO - docopt == 0.6.1 03:16:01 INFO - functools32 == 3.2.3-2 03:16:01 INFO - jsonschema == 2.5.1 03:16:01 INFO - manifestparser == 1.1 03:16:01 INFO - mozInstall == 1.12 03:16:01 INFO - mozcrash == 0.17 03:16:01 INFO - mozdebug == 0.1 03:16:01 INFO - mozdevice == 0.48 03:16:01 INFO - mozfile == 1.2 03:16:01 INFO - mozhttpd == 0.7 03:16:01 INFO - mozinfo == 0.9 03:16:01 INFO - mozleak == 0.1 03:16:01 INFO - mozlog == 3.2 03:16:01 INFO - moznetwork == 0.27 03:16:01 INFO - mozprocess == 0.23 03:16:01 INFO - mozprofile == 0.28 03:16:01 INFO - mozrunner == 6.12 03:16:01 INFO - mozscreenshot == 0.1 03:16:01 INFO - mozsystemmonitor == 0.3 03:16:01 INFO - moztest == 0.7 03:16:01 INFO - mozversion == 1.4 03:16:01 INFO - psutil == 3.1.1 03:16:01 INFO - requests == 1.2.3 03:16:01 INFO - wsgiref == 0.1.2 03:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:01 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 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:16:01 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 03:16:01 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 03:16:01 INFO - Using env: {'DISPLAY': ':0', 03:16:01 INFO - 'HOME': '/home/cltbld', 03:16:01 INFO - 'LANG': 'en_US.UTF-8', 03:16:01 INFO - 'LANGUAGE': 'en_US:en', 03:16:01 INFO - 'LOGNAME': 'cltbld', 03:16:01 INFO - 'MAIL': '/var/mail/cltbld', 03:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:01 INFO - 'MOZ_NO_REMOTE': '1', 03:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:01 INFO - 'NO_EM_RESTART': '1', 03:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:01 INFO - 'PWD': '/builds/slave/test', 03:16:01 INFO - 'SHELL': '/bin/bash', 03:16:01 INFO - 'SHLVL': '1', 03:16:01 INFO - 'TERM': 'linux', 03:16:01 INFO - 'TMOUT': '86400', 03:16:01 INFO - 'USER': 'cltbld', 03:16:01 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:01 INFO - '_': '/tools/buildbot/bin/python'} 03:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 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)) 03:16:01 INFO - Cleaning up... 03:16:01 INFO - Return code: 0 03:16:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', '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 03:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:16:01 INFO - Using env: {'DISPLAY': ':0', 03:16:01 INFO - 'HOME': '/home/cltbld', 03:16:01 INFO - 'LANG': 'en_US.UTF-8', 03:16:01 INFO - 'LANGUAGE': 'en_US:en', 03:16:01 INFO - 'LOGNAME': 'cltbld', 03:16:01 INFO - 'MAIL': '/var/mail/cltbld', 03:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:01 INFO - 'MOZ_NO_REMOTE': '1', 03:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:01 INFO - 'NO_EM_RESTART': '1', 03:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:01 INFO - 'PWD': '/builds/slave/test', 03:16:01 INFO - 'SHELL': '/bin/bash', 03:16:01 INFO - 'SHLVL': '1', 03:16:01 INFO - 'TERM': 'linux', 03:16:01 INFO - 'TMOUT': '86400', 03:16:01 INFO - 'USER': 'cltbld', 03:16:01 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:01 INFO - '_': '/tools/buildbot/bin/python'} 03:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:01 INFO - Cleaning up... 03:16:01 INFO - Return code: 0 03:16:01 INFO - Running post-action listener: _resource_record_post_action 03:16:01 INFO - Running post-action listener: _start_resource_monitoring 03:16:01 INFO - Starting resource monitoring. 03:16:01 INFO - ##### 03:16:01 INFO - ##### Finished create-virtualenv step (success) 03:16:01 INFO - ##### 03:16:01 INFO - ##### 03:16:01 INFO - ##### Running install step. 03:16:01 INFO - ##### 03:16:01 INFO - Running pre-action listener: _resource_record_pre_action 03:16:01 INFO - Running main action method: install 03:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:01 INFO - Reading from file tmpfile_stdout 03:16:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:02 INFO - Reading from file tmpfile_stdout 03:16:02 INFO - Output received: 03:16:02 INFO - Usage: mozinstall [options] installer 03:16:02 INFO - Options: 03:16:02 INFO - -h, --help show this help message and exit 03:16:02 INFO - -d DEST, --destination=DEST 03:16:02 INFO - Directory to install application into. [default: 03:16:02 INFO - "/builds/slave/test"] 03:16:02 INFO - --app=APP Application being installed. [default: firefox] 03:16:02 INFO - mkdir: /builds/slave/test/build/application 03:16:02 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'] 03:16:02 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 03:16:19 INFO - Reading from file tmpfile_stdout 03:16:19 INFO - Output received: 03:16:19 INFO - /builds/slave/test/build/application/firefox/firefox 03:16:19 INFO - Running post-action listener: _resource_record_post_action 03:16:19 INFO - ##### 03:16:19 INFO - ##### Finished install step (success) 03:16:19 INFO - ##### 03:16:19 INFO - ##### 03:16:19 INFO - ##### Running run-tests step. 03:16:19 INFO - ##### 03:16:19 INFO - Running pre-action listener: _resource_record_pre_action 03:16:19 INFO - Running main action method: run_tests 03:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:19 INFO - Python 2.7.3 03:16:19 INFO - Return code: 0 03:16:19 INFO - grabbing minidump binary from tooltool 03:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:19 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 0x1f9a3b0>, '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 0x1f9b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:19 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 03:16:19 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 03:16:19 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 03:16:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:19 INFO - Return code: 0 03:16:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:16:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:16:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 03:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 03:16:19 INFO - Using env: {'DISPLAY': ':0', 03:16:19 INFO - 'HOME': '/home/cltbld', 03:16:19 INFO - 'LANG': 'en_US.UTF-8', 03:16:19 INFO - 'LANGUAGE': 'en_US:en', 03:16:19 INFO - 'LOGNAME': 'cltbld', 03:16:19 INFO - 'MAIL': '/var/mail/cltbld', 03:16:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:19 INFO - 'MOZ_NO_REMOTE': '1', 03:16:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:19 INFO - 'NO_EM_RESTART': '1', 03:16:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:19 INFO - 'PWD': '/builds/slave/test', 03:16:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:19 INFO - 'SHELL': '/bin/bash', 03:16:19 INFO - 'SHLVL': '1', 03:16:19 INFO - 'TERM': 'linux', 03:16:19 INFO - 'TMOUT': '86400', 03:16:19 INFO - 'USER': 'cltbld', 03:16:19 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195', 03:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:19 INFO - '_': '/tools/buildbot/bin/python'} 03:16:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 03:16:19 INFO - mozversion application_buildid: 20160709003001 03:16:19 INFO - mozversion application_changeset: e3dcf062007e280ccf06e7bed7ff904d0fb44418 03:16:19 INFO - mozversion application_display_name: Nightly 03:16:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:19 INFO - mozversion application_name: Firefox 03:16:19 INFO - mozversion application_remotingname: firefox 03:16:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:16:19 INFO - mozversion application_vendor: Mozilla 03:16:19 INFO - mozversion application_version: 50.0a1 03:16:19 INFO - mozversion platform_buildid: 20160709003001 03:16:19 INFO - mozversion platform_changeset: e3dcf062007e280ccf06e7bed7ff904d0fb44418 03:16:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:16:19 INFO - mozversion platform_version: 50.0a1 03:16:19 INFO - using testdate: 1468059379 03:16:19 INFO - actual date: 1468059379 03:16:19 INFO - starting webserver on 'localhost:53143' 03:16:19 INFO - SUITE-START | Running 2 tests 03:16:19 INFO - TEST-START | dromaeo_css 03:16:19 INFO - operating with platform_type : linux_ 03:16:19 INFO - Initialising browser for dromaeo_css test... 03:16:19 INFO - TEST-INFO | started process 24749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGWresV/profile http://localhost:53143/getInfo.html) 03:16:23 INFO - PROCESS | 24749 | __metrics Screen width/height:1600/1200 03:16:23 INFO - PROCESS | 24749 | colorDepth:24 03:16:23 INFO - PROCESS | 24749 | Browser inner width/height: 1024/697 03:16:23 INFO - PROCESS | 24749 | __metrics 03:16:24 INFO - PROCESS | 24749 | 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" 03:16:24 INFO - PROCESS | 24749 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:16:25 INFO - TEST-INFO | 24749: exit 0 03:16:25 INFO - Browser initialized. 03:16:25 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:25 INFO - TEST-INFO | started process 24831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGWresV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:37 INFO - PROCESS | 24831 | RSS: Main: 157831168 03:16:37 INFO - PROCESS | 24831 | 03:17:28 INFO - PROCESS | 24831 | 1468059448563 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] 03:19:36 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53143/tests/dromaeo/cssquery-ext.html) 03:19:37 INFO - PROCESS | 24831 | RSS: Main: 280506368 03:19:37 INFO - PROCESS | 24831 | 03:22:39 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-ext.html (next: http://localhost:53143/tests/dromaeo/cssquery-jquery.html) 03:22:40 INFO - PROCESS | 24831 | RSS: Main: 248102912 03:22:40 INFO - PROCESS | 24831 | 03:25:42 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53143/tests/dromaeo/cssquery-mootools.html) 03:25:43 INFO - PROCESS | 24831 | RSS: Main: 257441792 03:25:43 INFO - PROCESS | 24831 | 03:28:44 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53143/tests/dromaeo/cssquery-prototype.html) 03:28:45 INFO - PROCESS | 24831 | RSS: Main: 238383104 03:28:45 INFO - PROCESS | 24831 | 03:31:48 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53143/tests/dromaeo/cssquery-yui.html) 03:31:48 INFO - PROCESS | 24831 | RSS: Main: 222674944 03:31:48 INFO - PROCESS | 24831 | 03:34:51 INFO - PROCESS | 24831 | Cycle 1(1): loaded http://localhost:53143/tests/dromaeo/cssquery-yui.html (next: http://localhost:53143/tests/dromaeo/cssquery-dojo.html) 03:34:52 INFO - PROCESS | 24831 | RSS: Main: 277721088 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | __start_tp_report 03:34:52 INFO - PROCESS | 24831 | _x_x_mozilla_page_load 03:34:52 INFO - PROCESS | 24831 | _x_x_mozilla_page_load_details 03:34:52 INFO - PROCESS | 24831 | |i|pagename|runs| 03:34:52 INFO - PROCESS | 24831 | |0;dojo.html;2898.4782739106954;2928.2679330167457;2932.7067293270675;3023.10818308599;3058.8470576471177;5178.611604129686;5209.7134657593815;5304.867378315544;5383.515483606475;5448.863778405547;5229.084910140719;5266.131088370415;5298.390685071218;5326.627136100475;5365.517103460687;4767.427908650958;4869.780859861315;4870.65905386623;4886.682365646239;4913.926291105637;4935.728534930578;4955.1080805455185;4958;4975.104481193383;4982.103221420143;4690.671652984292;4734.053189362107;4742.905141897143;4844.467108618049;4850.781714822824;4763.3569468121705;4781.784819683123;4785.042991401733;4788.162071637485;4862.051899879525;4749.667523273372;4809.591184749301;4816.373871396696;4821.325014497973;4855.805767769285;5126.92309615356;5194.68831870089;5286.81496147633;5294.708791016493;5339.9733001335135;5628.099504079369;5678.205051292823;5712.028955077605;5719;5723.227364305807;5703.828885133411;5715.685637289947;5745.023346031185;5764.07774756037;5771.740271687784;5153.690778553261;5295.152775555931;5332.066888294533;5361.678299302054;5365.356157261153;5904.084866845646;5904.7342869570975;5904.822855314347;5905.995980683294;5906.734196961061;5659.066254068115;5659.26429564154;5671.149327600893;5671.829845104653;5672.177534257509;5482.314710661168;5612.214289999403;5670.06443936754;5676.318841739017;5756.280464941882;5659.151127330933;5666.263385759825;5666.433356664301;5672.744726487318;5676.063449530864;5645.01212287848;5652.236948011966;5675.829725108253;5675.886482270332;5677.403872593318;4250.022494826146;4257.723247988871;4260.360945858084;4272.764997925144;4285.335772955197;3790.8483660653815;3797.487339209227;3803.676687481595;3836.1368692044066;3836.7889766062576;5337.359516857924;5339.199120132012;5339.839804805862;5342.412334643186;5379.704116273564;5493.23094766733;5524.944750552443;5551.3893471718075;5551.694656793915;5567.081431563827;5249.42256351801;5251.028559716465;5251.632385733038;5254.7898084076205;5270.868228294324;4695.647826413033;4697.95302046982;4706;4710.199266124699;4748.335233067373;5653;5725.627834190765;5735.254416925773;5785.392533783976;5798.826035219035;5633.746481408264;5639.295088113986;5643.32280126371;5687.118496633028;5691.914621280601;5848.4151584841175;5859.9121013183985;5861.35525092231;5864.06175011996;5864.384239654775;5348.919766203432;5556.888862222814;5572.582056345871;5602.85992850182;5687.516561092353;5681.545476361798;5708.343540492897;5711.086226203787;5726.455986681259;5727.427257274239;5583.525400341016;5583.553315734813;5590.385057643737;5591.4688104629995;5597.216389705526;5372.973135134299;5528.668279903219;5587.8882422352135;5590.7763689453595;5608.495235428668;5489.039418101896;5531.806386776443;5532.059549876451;5535.4187810280955;5541.833744987657;5384.5423139032055;5615.943840561542;5625.943740562542;5631.662100273997;5644.6330988485615;5423.484768946943;5483.396826349019;5486.451354864482;5506.091494903456;5506.531944784578 03:34:52 INFO - PROCESS | 24831 | |1;ext.html;587.362711458068;614.8339948213983;620.9285932117806;623.8689875126223;637.1494055435993;31077.68922310776;43142.352864707056;44009.55990440094;44082.33876491851;44135.3379699304;47297;47299.29051064237;47644.52355476444;47663.28505072427;47827.52172478274;8892.333075019367;9031.729048128534;9056.230220431271;9072.274218062572;9074.59164337603;9045.683401080963;9092.909070909272;9111.134442227978;9127.406718563272;9154.038825923282;9137.817243655156;9185.081491850828;9188.678396256133;9193.862092068624;9202.539873006323;9067.727968160965;9111.589978450986;9112.362134650577;9115.90884091164;9120.315976301737;8837.646494140226;8946.731598052069;8959.73120806377;8966.86549701755;8972.282217422657;42787.35818962718;43412.78293608543;43431.56568434307;43492.13015739668;43520.782396087816;3653.853845846163;3668.853245870162;3727.5154229949935;3821.4458903458844;4258.787060646955;33062.64153955142;33864.15339616505;33867.83066084678;33915.321693566235;34246.14384640379;4397.780110994469;4400.515943246241;4421.867343979686;4429.844955426577;4480.5967462928165;385924;386199;386862.9999999972;386966;387316;51980.48019519832;52078;52324;52334;52488.73755631197;61208.69395652993;61243;61477.69261153666;61491.38508614946;61624;15061.472848450252;16615.750763738553;16928.322867085426;16946.406875759534;17470.912645436947;12826.422810973529;12847.229166250056;12889;12939.935300323627;12954.352282385844;14192.148471091768;15847.445339413065;16018.839811601967;16130.274137663833;16135.112568808594;6112.8777424452155;6407.967960160169;6490.643014634241;6609.900851487231;6725.19297684281;1011.4032720694678;1029.161233594618;1036.6371769880482;1104.6354702948008;1117.987622279893;32930.341393172;33140.66859331424;33275.83362083174;33531.4970275446;33656.66343336536;1092.3773449134076;1119.8432219489277;1127.9341022733438;1133.218079525125;1169.8771628979005;16820.15899205035;17316.220770065378;17517.386891458736;17526.386576469762;17596.498050594873;571019;574143;574873;574930;577648;59663;59709.402905971256;59958.40041599528;59976;59993.70003150043;1330.4478641363726;1357.2874241023344;1373.4231622718482;1387.2924808347614;1400.8739213470838;30873.281679580105;32677.836610816794;32851.50722739209;32913.34173316472;33533.664663353054;52295;52575.474245258585;52608.47391526036;52625;52695;487336;496650;498877;500828;501387;34146.48780268248;40131.799341003105;41169.794151029055;41637.79181104075;41774.37338439865;49272.75363623159;49692;50490.49509504858;50844.237336440725;53290.73354633202;42211.7889410551;42913.35629965491;47407.288890667354;48060.27909581429;48565;46976.7651161742;47163;47232.7638361806;47346.28980565363;47768.4507154052;47035.764821175675;47498.28752568783;48344.75827620839;49336.25995610141;49519.25721114258;38550.6144938547;39147.608523914394;40024.799876000434;40935.59064409437;41233.79383103185;30538.856814078597;30656.386872262876;30668.846655766578;31168.53247201339;31311.53032704557 03:34:52 INFO - PROCESS | 24831 | |2;jquery.html;394.9980250098749;395.26480745812785;396.78573570272056;402.32007906637784;417.4969162159598;13054.390834465228;18845.717314240297;20800.375988720345;20801.375958721244;21372.145114195453;13566.45734170632;19566.804331956675;19658.70511942322;19724.40826775199;20122.195112195495;5716.256886604746;5879.235699359077;5882.14708867214;5883;5914.053751399777;5807.970960145193;5844.970775146139;5875.794347197849;5891.941080589182;5895.88208235835;4157.3140431828715;4292.077203401268;6155.384461553854;6452.612843229398;6455.2253729552285;6264.624122552661;6504.349565043505;6537.803865884007;6597.142371491699;6600.108985286997;5652.802151924663;5726.9713651431475;5733.770649174028;5749.913751293734;5750.712464376765;23834.761652383426;24916.626250606256;25221.62167567488;25247.87376063108;25322.1137260195;2393.4973655532185;2456.545539075277;2626.763591276775;2719.2114286856786;2766.349907771672;14473;14635.70728585433;15259.389624415011;15411.768823467657;15948.840511594968;1820.6904826179582;1891.4042076745782;1915.4062240705425;1950.9122089506006;1954.8045195480481;73081;76718.6164069176;86227;87074.5646271771;90736;11101.944490277498;11170.832437513443;11178.888211117948;11329.60346387889;11541.596044138414;15725.292361843745;15786.447474338573;15789;15828.841711582967;15850.76223856643;7833.804154896059;8016;8106.189381061847;8128.878066829002;8143.51138931666;7524.285192906775;7559.20628334028;7582.696692132253;7784.338331241908;7802.492837965514;4482.932756008662;4581.404417425713;4813.013332266877;4882.389701287339;4888.7311197884455;1717.6908156531945;1768.3191971090964;1811.8369346758855;1819.9909000454913;1820.8088150743981;977.9951100244596;987.7579992901785;1049.4437947887632;1077.7898309829666;1092.4537731134433;19470.929098899414;19647.214111435384;20020.799792002184;20653.89673051625;20844.374668759963;993.6522217223913;995.3579940938055;1017.9389236645825;1030.5006215955766;1046.728971962617;10624.36253824773;11737.178397512263;11820.399512107322;11872.643820685393;11926.940365298291;477306;484470;521781;526625;527137;101190;101847.49076254571;103071.48464257631;103458.48270758598;105123.4743826276;1016.501914062119;1046.215338496128;1053.5522402978704;1069.652362982034;1071.2554774431694;18223.271069157086;18297.353238208627;18377.81622183761;18427.26290948401;18834.340798072;45884.31173532333;47583.52416475791;47662;47762.522374777196;48970.26544601905;64722;65161.674191628736;65743.34256657372;66063.33936660572;66600.33399666136;8992.86510702353;9008.054154313702;9098.408603440756;9280;9328.440293582407;11004.559817607205;14314.928425357806;14626.195559244345;14743.262836857899;14819.259037047888;12408.379581021094;12524.812127818272;12772.425240863997;12913.289769062792;12928.935355323163;12510.374481276081;12521.186122901981;12550.309732964775;12884.48462061507;13122.671933201746;12773.616791495899;12936.611901642966;12944.676383090122;13089.738205236032;13125.737485250433;8990.28077753765;9059.094090590888;9065.32010099232;9285.72142835716;9392.389494682824;9597.040295970348;12217.389130543615;12726.745465090831;12852.614421567368;13027.413766380725 03:34:52 INFO - PROCESS | 24831 | |3;mootools.html;1314.8159257703928;1483.1175450606888;1490.9254537273137;1625.6586116915448;1741.5733145379384;8383.58082095895;8800.735977920665;8804.77988050299;9072.138146876036;9072.682456114037;8810.251128654072;8967.506787126722;8983.820323593523;9003.95498022509;9004.954975225131;2220.2784095169054;2231.062953559501;2249.156566287642;2250.9437264068365;2257.695211146499;2258.480549473614;2286.8170546356337;2304.078368652544;2307.4231442139467;2309.8960546775434;2203.0967303405605;2209.1384360099573;2229.777022297765;2231.386368748591;2234.1175235781857;2133.477298061969;2136.177571634924;2157.503774131943;2196.2642514757576;2210.745764237109;2185.4754858833926;2208.4810069633622;2210.4031911383836;2210.458437682762;2210.6462965925375;8869.334799890034;9049.954750226207;9097;9100.544972751335;9108.544572771334;5144.56271216947;5207.739613019334;5223.6343455958095;5226.503482169188;5226.843194704165;3276.836158192081;3286.408446479633;3337.1990722226733;3358.143673363277;3361.798292102482;1094.4910616563336;1099.4173088263233;1101.7741363020561;1105.7125147461638;1115.0158790207838;12669.113162078658;12724.491020359175;12774.808377874338;12776.936115319457;12783.744325113446;4291.463567054118;4343.305071188594;4505.36924830524;4505.414296141546;4509.7970591323465;9912.058354456316;10004.649837255807;10007.14939230159;10021.599136034478;10028.949855250678;5275.525202731773;5290.047791397508;5291.3121294231505;5293.100172970528;5296.947030529645;4275.4441922550495;4403.207422663952;4821.783019764119;4920.803167873245;4931.087748766489;3777.150141218259;3799.259144466858;3842.1547259602844;3878.534575850916;3891.805409729502;1154.2728081308721;1159.9478023488964;1164.5341863254596;1168;1174.8017022637034;980.2011360740973;992.4640694025289;999.8650182225452;1001.4742260313249;1001.5493028137367;5908.231929849178;6058.848528786815;6119.051547010221;6150.907736383958;6256.562040657111;418.46854494791444;425.05849543261826;431.6790620700666;438.3490516582872;439.47263284059255;3966.781826999543;4136.883041578757;4148.979255103705;4161.854335098257;4239.554846741109;11176.217664763552;11481.311121332747;11493.367864767351;11546.94226528862;11560.94219528897;10931.945340273407;11295.661130166109;11431.942840285745;11436.485358158903;11443.25618834773;8221.79445513867;8279.130691277449;8336.708215212317;8383.077861435237;8389.706360277361;6143.754249829957;6415.679216039272;6453.257875344396;6529.151210342815;6604.7027883746305;10200.132988696045;10456.424896630759;10581.947090264499;10700.946495267473;10721.195910306602;9421.811563768822;10280.742981425525;10620.309679870783;10947.233693641369;11028.779424411627;7949.364050875801;8098.959505202436;8332.208440198172;8359.623816928375;8372.330213582776;8808.5595720215;8900.42147260426;8920.553972301488;8924.68763593271;8933.776655583662;7866.331361834308;7876.133625301327;8117.79705507367;8176.550289734122;8279.793005174919;7643.464957452925;7793.493422927491;7804.648790804541;7911.960440197992;8099.230573095547;7429.925700742923;7437.367823735043;7449.925500744923;7498.025256716592;7499.550026998398;5357.705326207096;5426.8371948841595;5475.780968761205;5570.192322113352;5618.213450116905;5530.142827861688;5554.08357620988;5601.971990140023;5636.4927156556105;5704.001799685121 03:34:52 INFO - PROCESS | 24831 | |4;prototype.html;328.71566095327546;333.7430178762353;336.39952684458245;339.94220982432984;343.9982800085999;5206.219067139926;5330.147176451768;5338.878835444557;5367.892642147155;5422.8915421691545;5261.2371206175085;5270.367555893288;5274.340707411574;5290.68255904646;5313.362396512414;4120.443740095079;4142.316517774563;4152.813123409439;4160.479940007499;4186.099988502471;3840.635139661728;3997.2605068062358;4072.4705788247634;4139.710220284582;4186.351115577075;4079.4900637420324;4090.550039495668;4129.380592911058;4131.7894926214485;4137.731047481919;4098.9590104099025;4126.546079931205;4139.441175441307;4142;4147.979260103711;3414.4878268259713;3519.260955199411;3559.7929283844182;3585.8565657373674;3595.7482976191677;5130.333056702605;5156.407013193456;5156.576409087723;5163.509466600667;5167.010700880188;4697.342372067913;4697.741624210667;4707.529247075299;4711.044916359707;4711.269753188261;5407.513323800877;5461.590380721422;5462.180672899057;5462.754176062087;5479.589030822664;3893.80530973453;3906.902327441808;3912.4522566840665;3927.155661532784;3932.3315036443873;5529.815722789475;5560.388357280696;5705.400932902027;5731.656100633975;5740.799072032459;4023.8189281482405;4026.255142798592;4027.597240275949;4028.939565906514;4058.817353219112;4080.6327430531396;4105.7331273467125;4107.158032603309;4124.628783409507;4128.215639028635;4548.590626843534;4707.435107787019;4711.976440117777;4714.127886341038;4722.574968252874;5488.56091512678;5505.284313039359;5509.917351239735;5567.860803479945;5573.191887176336;1398.8179987910403;1420.8579142085916;1425.9714805703827;1432.5630682641895;1436.1096120405207;874.4665753890122;909.6134142989204;918.4305730447165;935.6257497001254;939.4598106088889;4760.285957106462;4763.356946812205;4776.223776223776;4787.0904528139545;4794.712317260975;4999.800007999712;4999.875003124878;5016.570277470903;5074.264231686385;5082.771275292621;4777.727256829083;4784;4791.658335666019;4795.657215979531;5052.216906379517;3012.7288544031144;3039.7112274333904;3055.8624861881267;3058.495348267511;3061.678523754974;5886.735096920649;5890.646561206341;5903.557233207526;5937.495312898365;5941.168236446901;5565.888682226333;5781.739821708034;5791.305043394819;5804.564657650693;5854.15594087861;5821.854453638608;5830.5627077968475;5866.413358664099;5869.413058694096;5884.440978107073;5431.511163995259;5682.295311406644;5684.602077854676;5702.545850808017;5711.742971566373;5643.576731745054;5672.602917795714;5684.527230654513;5694.184896119693;5696.1455781633085;5636.154576813511;5744.310682718101;5900.11498275245;5907.881842363215;5940.851478713067;4083.2037752637953;4104.548499664975;4131.26486875507;4132.504099507958;4133.586641335963;5548.889022219614;5750;5770.221020162392;5787.971060144672;5794.971025144847;5525.696086715108;5537.5569954402745;5543.695096769555;5545.944540554543;5575.498205161555;5482.5613950883035;5492.615516913937;5508.7521061553125;5512.80705175317;5540.36285827135;5230.5815534757885;5430.864228394164;5441.700706461183;5475.75359108849;5508.283923089973;5591.161325801162;5597.328320601554;5600.35595906476;5611.270534830446;5629.859253518531;5422.5119739223655;5467.234587157722;5504.256925315031;5529.889402212014;5531.695756733418 03:34:52 INFO - PROCESS | 24831 | |5;yui.html;772.4399810137647;786.9645865936034;831.8211584509331;846.8264005878796;857.5926434943399;15291.847081529182;16690.83309166908;16727.66544669106;16733.247003884822;17409.390671326506;16833.66332673346;16853.494395168134;16894.915525422388;17005.40481083159;17025.233864476097;676.7834293026234;809.7530253272749;841.4908980067063;844.3245403677065;845.7800971747359;793.6150966781136;810.8256724804166;818.071488860145;824.9793755156139;826.3224156191925;867.3278209387744;872.4111224923183;873.9213470787628;874.654511467973;874.846901792187;689.5621280486877;836.5022811427191;863.6329559937033;866.5970323799432;866.8959724833029;631.1637080867822;632.7057918068075;632.7089538812152;633.4488994574704;633.8161933039413;16190;16242.187890605423;16255.105969171691;16261.83738162615;16269.593260168473;14616.707665846623;14927.40290388383;15049.774253386207;15216.239188040554;15274.541763747106;13630;13727.245001524912;13778.931105344409;13855.861441385558;13882.514112006133;4559.088182363541;4636.907261854744;4659.277811939155;4675.696079754839;4735.692180008288;87893.99999999936;88648.55675721644;89071.55464222637;90311;90709;11644.141240864996;11653.59212427578;11849.289042657469;11876.88123118775;12203.69490762738;21755.782442175692;21957.451063723536;21965;21970.231041913456;21980.230691925706;8811.515366654896;8869.423487473294;8899;8906.59920303588;9038.186563209343;4960.553550180429;4962.15643340633;4987.351644286292;5001.974990125026;5224;3475.3570589441033;3542.929141417157;3568.553930758655;3576.0344706929504;3585.964140358563;602.4155975191115;614.8800983808204;619.4239357397663;628.8773689130667;638.7476946606065;571.8191933657068;650.443870490733;723.2586598736232;725.9673314700851;742.417202496038;9885.654002109892;10022.74943126413;10359.844602330972;10409.791804164026;10657.84013239802;532.6271609873104;600.9519238460913;721.743780957763;728.6479422625732;731.5062332925254;4727.621790256771;4768;4883.438404583448;4986.526280003394;4987.276844857475;128595.35702321616;130557.34721326522;132856.67143328636;133082.33458832643;133313;114400;116622;117946.41026794811;117998;118372.40813795876;98113;99767;102284;102717;103129.48435257775;9284.58219380116;9348.298877584344;9376.29677774156;9406.43561386319;9605.463125899823;57831.71084144552;58808.705956469945;59582.40417595942;60043.39956600553;60881.39118608934;82997.17002830136;83432.58283708543;83907;86537.56731216304;86854;11971.880281197076;12024.579139730417;12108.636740897788;12247.391952309985;12384.75230495367;13709.931450343083;13812;13902;13971.860281397056;14341.56975290743;12500.24998500093;12719.80920286178;12840.61478155657;13425.530106446618;13516.729665406834;12382.5666101686;12727.172733772275;12852.228866268053;13078.803817942548;13592.728145436839;12326.876731232931;12577.056720745797;12675.93662031684;12974.610761677164;13029.543965961146;10579.682609521727;10639.414832184304;11032.558697652004;11142.562024512003;11296;10812.791538332229;11245.887541124484;11275.774484510428;11278.774424511628;11292.548298068314 03:34:52 INFO - PROCESS | 24831 | __end_tp_report 03:34:52 INFO - PROCESS | 24831 | __start_cc_report 03:34:52 INFO - PROCESS | 24831 | _x_x_mozilla_cycle_collect,1057 03:34:52 INFO - PROCESS | 24831 | __end_cc_report 03:34:52 INFO - PROCESS | 24831 | __startTimestamp1468060492012__endTimestamp 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | ------- Summary: start ------- 03:34:52 INFO - PROCESS | 24831 | Number of tests: 6 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#0] dojo.html Cycles:165 Average:5256.62 Median:5489.04 stddev:620.62 (11.3%) stddev-sans-first:594.31 03:34:52 INFO - PROCESS | 24831 | Values: 2898.5 2928.3 2932.7 3023.1 3058.8 5178.6 5209.7 5304.9 5383.5 5448.9 5229.1 5266.1 5298.4 5326.6 5365.5 4767.4 4869.8 4870.7 4886.7 4913.9 4935.7 4955.1 4958.0 4975.1 4982.1 4690.7 4734.1 4742.9 4844.5 4850.8 4763.4 4781.8 4785.0 4788.2 4862.1 4749.7 4809.6 4816.4 4821.3 4855.8 5126.9 5194.7 5286.8 5294.7 5340.0 5628.1 5678.2 5712.0 5719.0 5723.2 5703.8 5715.7 5745.0 5764.1 5771.7 5153.7 5295.2 5332.1 5361.7 5365.4 5904.1 5904.7 5904.8 5906.0 5906.7 5659.1 5659.3 5671.1 5671.8 5672.2 5482.3 5612.2 5670.1 5676.3 5756.3 5659.2 5666.3 5666.4 5672.7 5676.1 5645.0 5652.2 5675.8 5675.9 5677.4 4250.0 4257.7 4260.4 4272.8 4285.3 3790.8 3797.5 3803.7 3836.1 3836.8 5337.4 5339.2 5339.8 5342.4 5379.7 5493.2 5524.9 5551.4 5551.7 5567.1 5249.4 5251.0 5251.6 5254.8 5270.9 4695.6 4698.0 4706.0 4710.2 4748.3 5653.0 5725.6 5735.3 5785.4 5798.8 5633.7 5639.3 5643.3 5687.1 5691.9 5848.4 5859.9 5861.4 5864.1 5864.4 5348.9 5556.9 5572.6 5602.9 5687.5 5681.5 5708.3 5711.1 5726.5 5727.4 5583.5 5583.6 5590.4 5591.5 5597.2 5373.0 5528.7 5587.9 5590.8 5608.5 5489.0 5531.8 5532.1 5535.4 5541.8 5384.5 5615.9 5625.9 5631.7 5644.6 5423.5 5483.4 5486.5 5506.1 5506.5 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#1] ext.html Cycles:180 Average:65157.67 Median:4455.22 stddev:130609.25 (2931.6%) stddev-sans-first:130885.67 03:34:52 INFO - PROCESS | 24831 | Values: 587.4 614.8 620.9 623.9 637.1 31077.7 43142.4 44009.6 44082.3 44135.3 47297.0 47299.3 47644.5 47663.3 47827.5 8892.3 9031.7 9056.2 9072.3 9074.6 9045.7 9092.9 9111.1 9127.4 9154.0 9137.8 9185.1 9188.7 9193.9 9202.5 9067.7 9111.6 9112.4 9115.9 9120.3 8837.6 8946.7 8959.7 8966.9 8972.3 42787.4 43412.8 43431.6 43492.1 43520.8 3653.9 3668.9 3727.5 3821.4 4258.8 33062.6 33864.2 33867.8 33915.3 34246.1 4397.8 4400.5 4421.9 4429.8 4480.6 385924.0 386199.0 386863.0 386966.0 387316.0 51980.5 52078.0 52324.0 52334.0 52488.7 61208.7 61243.0 61477.7 61491.4 61624.0 15061.5 16615.8 16928.3 16946.4 17470.9 12826.4 12847.2 12889.0 12939.9 12954.4 14192.1 15847.4 16018.8 16130.3 16135.1 6112.9 6408.0 6490.6 6609.9 6725.2 1011.4 1029.2 1036.6 1104.6 1118.0 32930.3 33140.7 33275.8 33531.5 33656.7 1092.4 1119.8 1127.9 1133.2 1169.9 16820.2 17316.2 17517.4 17526.4 17596.5 571019.0 574143.0 574873.0 574930.0 577648.0 59663.0 59709.4 59958.4 59976.0 59993.7 1330.4 1357.3 1373.4 1387.3 1400.9 30873.3 32677.8 32851.5 32913.3 33533.7 52295.0 52575.5 52608.5 52625.0 52695.0 487336.0 496650.0 498877.0 500828.0 501387.0 34146.5 40131.8 41169.8 41637.8 41774.4 49272.8 49692.0 50490.5 50844.2 53290.7 42211.8 42913.4 47407.3 48060.3 48565.0 46976.8 47163.0 47232.8 47346.3 47768.5 47035.8 47498.3 48344.8 49336.3 49519.3 38550.6 39147.6 40024.8 40935.6 41233.8 30538.9 30656.4 30668.8 31168.5 31311.5 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#2] jquery.html Cycles:180 Average:30653.47 Median:13114.13 stddev:83967.08 (640.3%) stddev-sans-first:84171.90 03:34:52 INFO - PROCESS | 24831 | Values: 395.0 395.3 396.8 402.3 417.5 13054.4 18845.7 20800.4 20801.4 21372.1 13566.5 19566.8 19658.7 19724.4 20122.2 5716.3 5879.2 5882.1 5883.0 5914.1 5808.0 5845.0 5875.8 5891.9 5895.9 4157.3 4292.1 6155.4 6452.6 6455.2 6264.6 6504.3 6537.8 6597.1 6600.1 5652.8 5727.0 5733.8 5749.9 5750.7 23834.8 24916.6 25221.6 25247.9 25322.1 2393.5 2456.5 2626.8 2719.2 2766.3 14473.0 14635.7 15259.4 15411.8 15948.8 1820.7 1891.4 1915.4 1950.9 1954.8 73081.0 76718.6 86227.0 87074.6 90736.0 11101.9 11170.8 11178.9 11329.6 11541.6 15725.3 15786.4 15789.0 15828.8 15850.8 7833.8 8016.0 8106.2 8128.9 8143.5 7524.3 7559.2 7582.7 7784.3 7802.5 4482.9 4581.4 4813.0 4882.4 4888.7 1717.7 1768.3 1811.8 1820.0 1820.8 978.0 987.8 1049.4 1077.8 1092.5 19470.9 19647.2 20020.8 20653.9 20844.4 993.7 995.4 1017.9 1030.5 1046.7 10624.4 11737.2 11820.4 11872.6 11926.9 477306.0 484470.0 521781.0 526625.0 527137.0 101190.0 101847.5 103071.5 103458.5 105123.5 1016.5 1046.2 1053.6 1069.7 1071.3 18223.3 18297.4 18377.8 18427.3 18834.3 45884.3 47583.5 47662.0 47762.5 48970.3 64722.0 65161.7 65743.3 66063.3 66600.3 8992.9 9008.1 9098.4 9280.0 9328.4 11004.6 14314.9 14626.2 14743.3 14819.3 12408.4 12524.8 12772.4 12913.3 12928.9 12510.4 12521.2 12550.3 12884.5 13122.7 12773.6 12936.6 12944.7 13089.7 13125.7 8990.3 9059.1 9065.3 9285.7 9392.4 9597.0 12217.4 12726.7 12852.6 13027.4 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#3] mootools.html Cycles:180 Average:5916.75 Median:2390.83 stddev:3468.89 (145.1%) stddev-sans-first:3461.39 03:34:52 INFO - PROCESS | 24831 | Values: 1314.8 1483.1 1490.9 1625.7 1741.6 8383.6 8800.7 8804.8 9072.1 9072.7 8810.3 8967.5 8983.8 9004.0 9005.0 2220.3 2231.1 2249.2 2250.9 2257.7 2258.5 2286.8 2304.1 2307.4 2309.9 2203.1 2209.1 2229.8 2231.4 2234.1 2133.5 2136.2 2157.5 2196.3 2210.7 2185.5 2208.5 2210.4 2210.5 2210.6 8869.3 9050.0 9097.0 9100.5 9108.5 5144.6 5207.7 5223.6 5226.5 5226.8 3276.8 3286.4 3337.2 3358.1 3361.8 1094.5 1099.4 1101.8 1105.7 1115.0 12669.1 12724.5 12774.8 12776.9 12783.7 4291.5 4343.3 4505.4 4505.4 4509.8 9912.1 10004.6 10007.1 10021.6 10028.9 5275.5 5290.0 5291.3 5293.1 5296.9 4275.4 4403.2 4821.8 4920.8 4931.1 3777.2 3799.3 3842.2 3878.5 3891.8 1154.3 1159.9 1164.5 1168.0 1174.8 980.2 992.5 999.9 1001.5 1001.5 5908.2 6058.8 6119.1 6150.9 6256.6 418.5 425.1 431.7 438.3 439.5 3966.8 4136.9 4149.0 4161.9 4239.6 11176.2 11481.3 11493.4 11546.9 11560.9 10931.9 11295.7 11431.9 11436.5 11443.3 8221.8 8279.1 8336.7 8383.1 8389.7 6143.8 6415.7 6453.3 6529.2 6604.7 10200.1 10456.4 10581.9 10700.9 10721.2 9421.8 10280.7 10620.3 10947.2 11028.8 7949.4 8099.0 8332.2 8359.6 8372.3 8808.6 8900.4 8920.6 8924.7 8933.8 7866.3 7876.1 8117.8 8176.6 8279.8 7643.5 7793.5 7804.6 7912.0 8099.2 7429.9 7437.4 7449.9 7498.0 7499.6 5357.7 5426.8 5475.8 5570.2 5618.2 5530.1 5554.1 5602.0 5636.5 5704.0 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#4] prototype.html Cycles:180 Average:4619.14 Median:5218.40 stddev:1356.69 (26.0%) stddev-sans-first:1321.73 03:34:52 INFO - PROCESS | 24831 | Values: 328.7 333.7 336.4 339.9 344.0 5206.2 5330.1 5338.9 5367.9 5422.9 5261.2 5270.4 5274.3 5290.7 5313.4 4120.4 4142.3 4152.8 4160.5 4186.1 3840.6 3997.3 4072.5 4139.7 4186.4 4079.5 4090.6 4129.4 4131.8 4137.7 4099.0 4126.5 4139.4 4142.0 4148.0 3414.5 3519.3 3559.8 3585.9 3595.7 5130.3 5156.4 5156.6 5163.5 5167.0 4697.3 4697.7 4707.5 4711.0 4711.3 5407.5 5461.6 5462.2 5462.8 5479.6 3893.8 3906.9 3912.5 3927.2 3932.3 5529.8 5560.4 5705.4 5731.7 5740.8 4023.8 4026.3 4027.6 4028.9 4058.8 4080.6 4105.7 4107.2 4124.6 4128.2 4548.6 4707.4 4712.0 4714.1 4722.6 5488.6 5505.3 5509.9 5567.9 5573.2 1398.8 1420.9 1426.0 1432.6 1436.1 874.5 909.6 918.4 935.6 939.5 4760.3 4763.4 4776.2 4787.1 4794.7 4999.8 4999.9 5016.6 5074.3 5082.8 4777.7 4784.0 4791.7 4795.7 5052.2 3012.7 3039.7 3055.9 3058.5 3061.7 5886.7 5890.6 5903.6 5937.5 5941.2 5565.9 5781.7 5791.3 5804.6 5854.2 5821.9 5830.6 5866.4 5869.4 5884.4 5431.5 5682.3 5684.6 5702.5 5711.7 5643.6 5672.6 5684.5 5694.2 5696.1 5636.2 5744.3 5900.1 5907.9 5940.9 4083.2 4104.5 4131.3 4132.5 4133.6 5548.9 5750.0 5770.2 5788.0 5795.0 5525.7 5537.6 5543.7 5545.9 5575.5 5482.6 5492.6 5508.8 5512.8 5540.4 5230.6 5430.9 5441.7 5475.8 5508.3 5591.2 5597.3 5600.4 5611.3 5629.9 5422.5 5467.2 5504.3 5529.9 5531.7 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - PROCESS | 24831 | [#5] yui.html Cycles:180 Average:23222.85 Median:3581.00 stddev:35012.90 (977.7%) stddev-sans-first:35070.55 03:34:52 INFO - PROCESS | 24831 | Values: 772.4 787.0 831.8 846.8 857.6 15291.8 16690.8 16727.7 16733.2 17409.4 16833.7 16853.5 16894.9 17005.4 17025.2 676.8 809.8 841.5 844.3 845.8 793.6 810.8 818.1 825.0 826.3 867.3 872.4 873.9 874.7 874.8 689.6 836.5 863.6 866.6 866.9 631.2 632.7 632.7 633.4 633.8 16190.0 16242.2 16255.1 16261.8 16269.6 14616.7 14927.4 15049.8 15216.2 15274.5 13630.0 13727.2 13778.9 13855.9 13882.5 4559.1 4636.9 4659.3 4675.7 4735.7 87894.0 88648.6 89071.6 90311.0 90709.0 11644.1 11653.6 11849.3 11876.9 12203.7 21755.8 21957.5 21965.0 21970.2 21980.2 8811.5 8869.4 8899.0 8906.6 9038.2 4960.6 4962.2 4987.4 5002.0 5224.0 3475.4 3542.9 3568.6 3576.0 3586.0 602.4 614.9 619.4 628.9 638.7 571.8 650.4 723.3 726.0 742.4 9885.7 10022.7 10359.8 10409.8 10657.8 532.6 601.0 721.7 728.6 731.5 4727.6 4768.0 4883.4 4986.5 4987.3 128595.4 130557.3 132856.7 133082.3 133313.0 114400.0 116622.0 117946.4 117998.0 118372.4 98113.0 99767.0 102284.0 102717.0 103129.5 9284.6 9348.3 9376.3 9406.4 9605.5 57831.7 58808.7 59582.4 60043.4 60881.4 82997.2 83432.6 83907.0 86537.6 86854.0 11971.9 12024.6 12108.6 12247.4 12384.8 13709.9 13812.0 13902.0 13971.9 14341.6 12500.2 12719.8 12840.6 13425.5 13516.7 12382.6 12727.2 12852.2 13078.8 13592.7 12326.9 12577.1 12675.9 12974.6 13029.5 10579.7 10639.4 11032.6 11142.6 11296.0 10812.8 11245.9 11275.8 11278.8 11292.5 03:34:52 INFO - PROCESS | 24831 | -------- Summary: end -------- 03:34:52 INFO - PROCESS | 24831 | 03:34:52 INFO - TEST-INFO | 24831: exit 0 03:34:52 INFO - TEST-OK | dromaeo_css | took 1113268ms 03:34:52 INFO - TEST-START | kraken 03:34:52 INFO - operating with platform_type : linux_ 03:34:52 INFO - Initialising browser for kraken test... 03:34:52 INFO - TEST-INFO | started process 25020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqOVJ36/profile http://localhost:53143/getInfo.html) 03:34:56 INFO - PROCESS | 25020 | __metrics Screen width/height:1600/1200 03:34:56 INFO - PROCESS | 25020 | colorDepth:24 03:34:56 INFO - PROCESS | 25020 | Browser inner width/height: 1024/697 03:34:56 INFO - PROCESS | 25020 | __metrics 03:34:57 INFO - PROCESS | 25020 | 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" 03:34:57 INFO - PROCESS | 25020 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:34:58 INFO - TEST-INFO | 25020: exit 0 03:34:58 INFO - Browser initialized. 03:34:58 INFO - Running cycle 1/1 for kraken test... 03:34:58 INFO - TEST-INFO | started process 25104 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqOVJ36/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:10 INFO - PROCESS | 25104 | RSS: Main: 157528064 03:35:10 INFO - PROCESS | 25104 | 03:35:12 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53143/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:13 INFO - PROCESS | 25104 | RSS: Main: 214986752 03:35:13 INFO - PROCESS | 25104 | 03:35:16 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53143/tests/kraken/driver.html?testName=audio-dft) 03:35:16 INFO - PROCESS | 25104 | RSS: Main: 202772480 03:35:16 INFO - PROCESS | 25104 | 03:35:19 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53143/tests/kraken/driver.html?testName=audio-fft) 03:35:20 INFO - PROCESS | 25104 | RSS: Main: 269541376 03:35:20 INFO - PROCESS | 25104 | 03:35:22 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53143/tests/kraken/driver.html?testName=audio-oscillator) 03:35:23 INFO - PROCESS | 25104 | RSS: Main: 180322304 03:35:23 INFO - PROCESS | 25104 | 03:35:25 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53143/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:25 INFO - PROCESS | 25104 | RSS: Main: 184905728 03:35:25 INFO - PROCESS | 25104 | 03:35:28 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53143/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:29 INFO - PROCESS | 25104 | RSS: Main: 218869760 03:35:29 INFO - PROCESS | 25104 | 03:35:32 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53143/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:33 INFO - PROCESS | 25104 | RSS: Main: 220258304 03:35:33 INFO - PROCESS | 25104 | 03:35:36 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53143/tests/kraken/driver.html?testName=json-parse-financial) 03:35:36 INFO - PROCESS | 25104 | RSS: Main: 221138944 03:35:36 INFO - PROCESS | 25104 | 03:35:38 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53143/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:39 INFO - PROCESS | 25104 | RSS: Main: 206647296 03:35:39 INFO - PROCESS | 25104 | 03:35:40 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:41 INFO - PROCESS | 25104 | RSS: Main: 210984960 03:35:41 INFO - PROCESS | 25104 | 03:35:43 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:35:44 INFO - PROCESS | 25104 | RSS: Main: 209326080 03:35:44 INFO - PROCESS | 25104 | 03:35:46 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:35:47 INFO - PROCESS | 25104 | RSS: Main: 224759808 03:35:47 INFO - PROCESS | 25104 | 03:35:49 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:35:50 INFO - PROCESS | 25104 | RSS: Main: 225009664 03:35:50 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | Cycle 1(1): loaded http://localhost:53143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53143/tests/kraken/driver.html?testName=ai-astar) 03:35:52 INFO - PROCESS | 25104 | RSS: Main: 268046336 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | __start_tp_report 03:35:52 INFO - PROCESS | 25104 | _x_x_mozilla_page_load 03:35:52 INFO - PROCESS | 25104 | _x_x_mozilla_page_load_details 03:35:52 INFO - PROCESS | 25104 | |i|pagename|runs| 03:35:52 INFO - PROCESS | 25104 | |0;ai-astar;101;111;110;112;111;110;110;109;111;111 03:35:52 INFO - PROCESS | 25104 | |1;audio-beat-detection;145;115;118;174;118;118;151;123;164;116 03:35:52 INFO - PROCESS | 25104 | |2;audio-dft;160;162;161;160;161;162;161;162;162;162 03:35:52 INFO - PROCESS | 25104 | |3;audio-fft;75;75;75;121;76;75;75;75;98;76 03:35:52 INFO - PROCESS | 25104 | |4;audio-oscillator;85;84;115;84;85;84;119;84;85;127 03:35:52 INFO - PROCESS | 25104 | |5;imaging-gaussian-blur;117;117;117;117;117;116;118;117;117;117 03:35:52 INFO - PROCESS | 25104 | |6;imaging-darkroom;148;152;151;148;151;151;153;153;150;147 03:35:52 INFO - PROCESS | 25104 | |7;imaging-desaturate;95;95;96;95;95;94;95;96;95;95 03:35:52 INFO - PROCESS | 25104 | |8;json-parse-financial;54;54;57;54;53;56;55;53;54;54 03:35:52 INFO - PROCESS | 25104 | |9;json-stringify-tinderbox;62;61;60;60;61;61;61;61;61;61 03:35:52 INFO - PROCESS | 25104 | |10;stanford-crypto-aes;75;77;78;80;81;75;77;76;75;75 03:35:52 INFO - PROCESS | 25104 | |11;stanford-crypto-ccm;118;117;116;117;117;119;119;126;162;128 03:35:52 INFO - PROCESS | 25104 | |12;stanford-crypto-pbkdf2;156;157;155;157;152;157;156;152;155;155 03:35:52 INFO - PROCESS | 25104 | |13;stanford-crypto-sha256-iterative;64;63;62;63;62;62;62;60;62;65 03:35:52 INFO - PROCESS | 25104 | __end_tp_report 03:35:52 INFO - PROCESS | 25104 | __start_cc_report 03:35:52 INFO - PROCESS | 25104 | _x_x_mozilla_cycle_collect,1673 03:35:52 INFO - PROCESS | 25104 | __end_cc_report 03:35:52 INFO - PROCESS | 25104 | __startTimestamp1468060552257__endTimestamp 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | ------- Summary: start ------- 03:35:52 INFO - PROCESS | 25104 | Number of tests: 14 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.13 (2.8%) stddev-sans-first:0.88 03:35:52 INFO - PROCESS | 25104 | Values: 101.0 111.0 110.0 112.0 111.0 110.0 110.0 109.0 111.0 111.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:134.00 stddev:22.32 (16.7%) stddev-sans-first:23.33 03:35:52 INFO - PROCESS | 25104 | Values: 145.0 115.0 118.0 174.0 118.0 118.0 151.0 123.0 164.0 116.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 03:35:52 INFO - PROCESS | 25104 | Values: 160.0 162.0 161.0 160.0 161.0 162.0 161.0 162.0 162.0 162.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:15.43 (20.6%) stddev-sans-first:16.15 03:35:52 INFO - PROCESS | 25104 | Values: 75.0 75.0 75.0 121.0 76.0 75.0 75.0 75.0 98.0 76.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.00 stddev:17.59 (20.9%) stddev-sans-first:18.26 03:35:52 INFO - PROCESS | 25104 | Values: 85.0 84.0 115.0 84.0 85.0 84.0 119.0 84.0 85.0 127.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 03:35:52 INFO - PROCESS | 25104 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 118.0 117.0 117.0 117.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:151.00 stddev:2.12 (1.4%) stddev-sans-first:2.06 03:35:52 INFO - PROCESS | 25104 | Values: 148.0 152.0 151.0 148.0 151.0 151.0 153.0 153.0 150.0 147.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:35:52 INFO - PROCESS | 25104 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 95.0 96.0 95.0 95.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:1.26 (2.3%) stddev-sans-first:1.33 03:35:52 INFO - PROCESS | 25104 | Values: 54.0 54.0 57.0 54.0 53.0 56.0 55.0 53.0 54.0 54.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 03:35:52 INFO - PROCESS | 25104 | Values: 62.0 61.0 60.0 60.0 61.0 61.0 61.0 61.0 61.0 61.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:77.00 stddev:2.18 (2.8%) stddev-sans-first:2.20 03:35:52 INFO - PROCESS | 25104 | Values: 75.0 77.0 78.0 80.0 81.0 75.0 77.0 76.0 75.0 75.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:119.00 stddev:13.99 (11.8%) stddev-sans-first:14.67 03:35:52 INFO - PROCESS | 25104 | Values: 118.0 117.0 116.0 117.0 117.0 119.0 119.0 126.0 162.0 128.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:156.00 stddev:1.87 (1.2%) stddev-sans-first:1.96 03:35:52 INFO - PROCESS | 25104 | Values: 156.0 157.0 155.0 157.0 152.0 157.0 156.0 152.0 155.0 155.0 03:35:52 INFO - PROCESS | 25104 | 03:35:52 INFO - PROCESS | 25104 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:1.35 (2.2%) stddev-sans-first:1.32 03:35:52 INFO - PROCESS | 25104 | Values: 64.0 63.0 62.0 63.0 62.0 62.0 62.0 60.0 62.0 65.0 03:35:52 INFO - PROCESS | 25104 | -------- Summary: end -------- 03:35:52 INFO - PROCESS | 25104 | 03:35:53 INFO - TEST-INFO | 25104: exit 0 03:35:53 INFO - TEST-OK | kraken | took 60467ms 03:35:53 INFO - SUITE-END | took 1173s 03:35:53 INFO - Completed test suite (00:19:33) 03:35:53 INFO - javascript benchmark 03:35:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2898.4782739106954, 2928.2679330167457, 2932.7067293270675, 3023.10818308599, 3058.8470576471177, 5178.611604129686, 5209.7134657593815, 5304.867378315544, 5383.515483606475, 5448.863778405547, 5229.084910140719, 5266.131088370415, 5298.390685071218, 5326.627136100475, 5365.517103460687, 4767.427908650958, 4869.780859861315, 4870.65905386623, 4886.682365646239, 4913.926291105637, 4935.728534930578, 4955.1080805455185, 4958.0, 4975.104481193383, 4982.103221420143, 4690.671652984292, 4734.053189362107, 4742.905141897143, 4844.467108618049, 4850.781714822824, 4763.3569468121705, 4781.784819683123, 4785.042991401733, 4788.162071637485, 4862.051899879525, 4749.667523273372, 4809.591184749301, 4816.373871396696, 4821.325014497973, 4855.805767769285, 5126.92309615356, 5194.68831870089, 5286.81496147633, 5294.708791016493, 5339.9733001335135, 5628.099504079369, 5678.205051292823, 5712.028955077605, 5719.0, 5723.227364305807, 5703.828885133411, 5715.685637289947, 5745.023346031185, 5764.07774756037, 5771.740271687784, 5153.690778553261, 5295.152775555931, 5332.066888294533, 5361.678299302054, 5365.356157261153, 5904.084866845646, 5904.7342869570975, 5904.822855314347, 5905.995980683294, 5906.734196961061, 5659.066254068115, 5659.26429564154, 5671.149327600893, 5671.829845104653, 5672.177534257509, 5482.314710661168, 5612.214289999403, 5670.06443936754, 5676.318841739017, 5756.280464941882, 5659.151127330933, 5666.263385759825, 5666.433356664301, 5672.744726487318, 5676.063449530864, 5645.01212287848, 5652.236948011966, 5675.829725108253, 5675.886482270332, 5677.403872593318, 4250.022494826146, 4257.723247988871, 4260.360945858084, 4272.764997925144, 4285.335772955197, 3790.8483660653815, 3797.487339209227, 3803.676687481595, 3836.1368692044066, 3836.7889766062576, 5337.359516857924, 5339.199120132012, 5339.839804805862, 5342.412334643186, 5379.704116273564, 5493.23094766733, 5524.944750552443, 5551.3893471718075, 5551.694656793915, 5567.081431563827, 5249.42256351801, 5251.028559716465, 5251.632385733038, 5254.7898084076205, 5270.868228294324, 4695.647826413033, 4697.95302046982, 4706.0, 4710.199266124699, 4748.335233067373, 5653.0, 5725.627834190765, 5735.254416925773, 5785.392533783976, 5798.826035219035, 5633.746481408264, 5639.295088113986, 5643.32280126371, 5687.118496633028, 5691.914621280601, 5848.4151584841175, 5859.9121013183985, 5861.35525092231, 5864.06175011996, 5864.384239654775, 5348.919766203432, 5556.888862222814, 5572.582056345871, 5602.85992850182, 5687.516561092353, 5681.545476361798, 5708.343540492897, 5711.086226203787, 5726.455986681259, 5727.427257274239, 5583.525400341016, 5583.553315734813, 5590.385057643737, 5591.4688104629995, 5597.216389705526, 5372.973135134299, 5528.668279903219, 5587.8882422352135, 5590.7763689453595, 5608.495235428668, 5489.039418101896, 5531.806386776443, 5532.059549876451, 5535.4187810280955, 5541.833744987657, 5384.5423139032055, 5615.943840561542, 5625.943740562542, 5631.662100273997, 5644.6330988485615, 5423.484768946943, 5483.396826349019, 5486.451354864482, 5506.091494903456, 5506.531944784578], "value": 5212.502907072528, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.362711458068, 614.8339948213983, 620.9285932117806, 623.8689875126223, 637.1494055435993, 31077.68922310776, 43142.352864707056, 44009.55990440094, 44082.33876491851, 44135.3379699304, 47297.0, 47299.29051064237, 47644.52355476444, 47663.28505072427, 47827.52172478274, 8892.333075019367, 9031.729048128534, 9056.230220431271, 9072.274218062572, 9074.59164337603, 9045.683401080963, 9092.909070909272, 9111.134442227978, 9127.406718563272, 9154.038825923282, 9137.817243655156, 9185.081491850828, 9188.678396256133, 9193.862092068624, 9202.539873006323, 9067.727968160965, 9111.589978450986, 9112.362134650577, 9115.90884091164, 9120.315976301737, 8837.646494140226, 8946.731598052069, 8959.73120806377, 8966.86549701755, 8972.282217422657, 42787.35818962718, 43412.78293608543, 43431.56568434307, 43492.13015739668, 43520.782396087816, 3653.853845846163, 3668.853245870162, 3727.5154229949935, 3821.4458903458844, 4258.787060646955, 33062.64153955142, 33864.15339616505, 33867.83066084678, 33915.321693566235, 34246.14384640379, 4397.780110994469, 4400.515943246241, 4421.867343979686, 4429.844955426577, 4480.5967462928165, 385924.0, 386199.0, 386862.9999999972, 386966.0, 387316.0, 51980.48019519832, 52078.0, 52324.0, 52334.0, 52488.73755631197, 61208.69395652993, 61243.0, 61477.69261153666, 61491.38508614946, 61624.0, 15061.472848450252, 16615.750763738553, 16928.322867085426, 16946.406875759534, 17470.912645436947, 12826.422810973529, 12847.229166250056, 12889.0, 12939.935300323627, 12954.352282385844, 14192.148471091768, 15847.445339413065, 16018.839811601967, 16130.274137663833, 16135.112568808594, 6112.8777424452155, 6407.967960160169, 6490.643014634241, 6609.900851487231, 6725.19297684281, 1011.4032720694678, 1029.161233594618, 1036.6371769880482, 1104.6354702948008, 1117.987622279893, 32930.341393172, 33140.66859331424, 33275.83362083174, 33531.4970275446, 33656.66343336536, 1092.3773449134076, 1119.8432219489277, 1127.9341022733438, 1133.218079525125, 1169.8771628979005, 16820.15899205035, 17316.220770065378, 17517.386891458736, 17526.386576469762, 17596.498050594873, 571019.0, 574143.0, 574873.0, 574930.0, 577648.0, 59663.0, 59709.402905971256, 59958.40041599528, 59976.0, 59993.70003150043, 1330.4478641363726, 1357.2874241023344, 1373.4231622718482, 1387.2924808347614, 1400.8739213470838, 30873.281679580105, 32677.836610816794, 32851.50722739209, 32913.34173316472, 33533.664663353054, 52295.0, 52575.474245258585, 52608.47391526036, 52625.0, 52695.0, 487336.0, 496650.0, 498877.0, 500828.0, 501387.0, 34146.48780268248, 40131.799341003105, 41169.794151029055, 41637.79181104075, 41774.37338439865, 49272.75363623159, 49692.0, 50490.49509504858, 50844.237336440725, 53290.73354633202, 42211.7889410551, 42913.35629965491, 47407.288890667354, 48060.27909581429, 48565.0, 46976.7651161742, 47163.0, 47232.7638361806, 47346.28980565363, 47768.4507154052, 47035.764821175675, 47498.28752568783, 48344.75827620839, 49336.25995610141, 49519.25721114258, 38550.6144938547, 39147.608523914394, 40024.799876000434, 40935.59064409437, 41233.79383103185, 30538.856814078597, 30656.386872262876, 30668.846655766578, 31168.53247201339, 31311.53032704557], "value": 20942.030744139105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.9980250098749, 395.26480745812785, 396.78573570272056, 402.32007906637784, 417.4969162159598, 13054.390834465228, 18845.717314240297, 20800.375988720345, 20801.375958721244, 21372.145114195453, 13566.45734170632, 19566.804331956675, 19658.70511942322, 19724.40826775199, 20122.195112195495, 5716.256886604746, 5879.235699359077, 5882.14708867214, 5883.0, 5914.053751399777, 5807.970960145193, 5844.970775146139, 5875.794347197849, 5891.941080589182, 5895.88208235835, 4157.3140431828715, 4292.077203401268, 6155.384461553854, 6452.612843229398, 6455.2253729552285, 6264.624122552661, 6504.349565043505, 6537.803865884007, 6597.142371491699, 6600.108985286997, 5652.802151924663, 5726.9713651431475, 5733.770649174028, 5749.913751293734, 5750.712464376765, 23834.761652383426, 24916.626250606256, 25221.62167567488, 25247.87376063108, 25322.1137260195, 2393.4973655532185, 2456.545539075277, 2626.763591276775, 2719.2114286856786, 2766.349907771672, 14473.0, 14635.70728585433, 15259.389624415011, 15411.768823467657, 15948.840511594968, 1820.6904826179582, 1891.4042076745782, 1915.4062240705425, 1950.9122089506006, 1954.8045195480481, 73081.0, 76718.6164069176, 86227.0, 87074.5646271771, 90736.0, 11101.944490277498, 11170.832437513443, 11178.888211117948, 11329.60346387889, 11541.596044138414, 15725.292361843745, 15786.447474338573, 15789.0, 15828.841711582967, 15850.76223856643, 7833.804154896059, 8016.0, 8106.189381061847, 8128.878066829002, 8143.51138931666, 7524.285192906775, 7559.20628334028, 7582.696692132253, 7784.338331241908, 7802.492837965514, 4482.932756008662, 4581.404417425713, 4813.013332266877, 4882.389701287339, 4888.7311197884455, 1717.6908156531945, 1768.3191971090964, 1811.8369346758855, 1819.9909000454913, 1820.8088150743981, 977.9951100244596, 987.7579992901785, 1049.4437947887632, 1077.7898309829666, 1092.4537731134433, 19470.929098899414, 19647.214111435384, 20020.799792002184, 20653.89673051625, 20844.374668759963, 993.6522217223913, 995.3579940938055, 1017.9389236645825, 1030.5006215955766, 1046.728971962617, 10624.36253824773, 11737.178397512263, 11820.399512107322, 11872.643820685393, 11926.940365298291, 477306.0, 484470.0, 521781.0, 526625.0, 527137.0, 101190.0, 101847.49076254571, 103071.48464257631, 103458.48270758598, 105123.4743826276, 1016.501914062119, 1046.215338496128, 1053.5522402978704, 1069.652362982034, 1071.2554774431694, 18223.271069157086, 18297.353238208627, 18377.81622183761, 18427.26290948401, 18834.340798072, 45884.31173532333, 47583.52416475791, 47662.0, 47762.522374777196, 48970.26544601905, 64722.0, 65161.674191628736, 65743.34256657372, 66063.33936660572, 66600.33399666136, 8992.86510702353, 9008.054154313702, 9098.408603440756, 9280.0, 9328.440293582407, 11004.559817607205, 14314.928425357806, 14626.195559244345, 14743.262836857899, 14819.259037047888, 12408.379581021094, 12524.812127818272, 12772.425240863997, 12913.289769062792, 12928.935355323163, 12510.374481276081, 12521.186122901981, 12550.309732964775, 12884.48462061507, 13122.671933201746, 12773.616791495899, 12936.611901642966, 12944.676383090122, 13089.738205236032, 13125.737485250433, 8990.28077753765, 9059.094090590888, 9065.32010099232, 9285.72142835716, 9392.389494682824, 9597.040295970348, 12217.389130543615, 12726.745465090831, 12852.614421567368, 13027.413766380725], "value": 9694.47163567294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.8159257703928, 1483.1175450606888, 1490.9254537273137, 1625.6586116915448, 1741.5733145379384, 8383.58082095895, 8800.735977920665, 8804.77988050299, 9072.138146876036, 9072.682456114037, 8810.251128654072, 8967.506787126722, 8983.820323593523, 9003.95498022509, 9004.954975225131, 2220.2784095169054, 2231.062953559501, 2249.156566287642, 2250.9437264068365, 2257.695211146499, 2258.480549473614, 2286.8170546356337, 2304.078368652544, 2307.4231442139467, 2309.8960546775434, 2203.0967303405605, 2209.1384360099573, 2229.777022297765, 2231.386368748591, 2234.1175235781857, 2133.477298061969, 2136.177571634924, 2157.503774131943, 2196.2642514757576, 2210.745764237109, 2185.4754858833926, 2208.4810069633622, 2210.4031911383836, 2210.458437682762, 2210.6462965925375, 8869.334799890034, 9049.954750226207, 9097.0, 9100.544972751335, 9108.544572771334, 5144.56271216947, 5207.739613019334, 5223.6343455958095, 5226.503482169188, 5226.843194704165, 3276.836158192081, 3286.408446479633, 3337.1990722226733, 3358.143673363277, 3361.798292102482, 1094.4910616563336, 1099.4173088263233, 1101.7741363020561, 1105.7125147461638, 1115.0158790207838, 12669.113162078658, 12724.491020359175, 12774.808377874338, 12776.936115319457, 12783.744325113446, 4291.463567054118, 4343.305071188594, 4505.36924830524, 4505.414296141546, 4509.7970591323465, 9912.058354456316, 10004.649837255807, 10007.14939230159, 10021.599136034478, 10028.949855250678, 5275.525202731773, 5290.047791397508, 5291.3121294231505, 5293.100172970528, 5296.947030529645, 4275.4441922550495, 4403.207422663952, 4821.783019764119, 4920.803167873245, 4931.087748766489, 3777.150141218259, 3799.259144466858, 3842.1547259602844, 3878.534575850916, 3891.805409729502, 1154.2728081308721, 1159.9478023488964, 1164.5341863254596, 1168.0, 1174.8017022637034, 980.2011360740973, 992.4640694025289, 999.8650182225452, 1001.4742260313249, 1001.5493028137367, 5908.231929849178, 6058.848528786815, 6119.051547010221, 6150.907736383958, 6256.562040657111, 418.46854494791444, 425.05849543261826, 431.6790620700666, 438.3490516582872, 439.47263284059255, 3966.781826999543, 4136.883041578757, 4148.979255103705, 4161.854335098257, 4239.554846741109, 11176.217664763552, 11481.311121332747, 11493.367864767351, 11546.94226528862, 11560.94219528897, 10931.945340273407, 11295.661130166109, 11431.942840285745, 11436.485358158903, 11443.25618834773, 8221.79445513867, 8279.130691277449, 8336.708215212317, 8383.077861435237, 8389.706360277361, 6143.754249829957, 6415.679216039272, 6453.257875344396, 6529.151210342815, 6604.7027883746305, 10200.132988696045, 10456.424896630759, 10581.947090264499, 10700.946495267473, 10721.195910306602, 9421.811563768822, 10280.742981425525, 10620.309679870783, 10947.233693641369, 11028.779424411627, 7949.364050875801, 8098.959505202436, 8332.208440198172, 8359.623816928375, 8372.330213582776, 8808.5595720215, 8900.42147260426, 8920.553972301488, 8924.68763593271, 8933.776655583662, 7866.331361834308, 7876.133625301327, 8117.79705507367, 8176.550289734122, 8279.793005174919, 7643.464957452925, 7793.493422927491, 7804.648790804541, 7911.960440197992, 8099.230573095547, 7429.925700742923, 7437.367823735043, 7449.925500744923, 7498.025256716592, 7499.550026998398, 5357.705326207096, 5426.8371948841595, 5475.780968761205, 5570.192322113352, 5618.213450116905, 5530.142827861688, 5554.08357620988, 5601.971990140023, 5636.4927156556105, 5704.001799685121], "value": 4581.445808166949, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.71566095327546, 333.7430178762353, 336.39952684458245, 339.94220982432984, 343.9982800085999, 5206.219067139926, 5330.147176451768, 5338.878835444557, 5367.892642147155, 5422.8915421691545, 5261.2371206175085, 5270.367555893288, 5274.340707411574, 5290.68255904646, 5313.362396512414, 4120.443740095079, 4142.316517774563, 4152.813123409439, 4160.479940007499, 4186.099988502471, 3840.635139661728, 3997.2605068062358, 4072.4705788247634, 4139.710220284582, 4186.351115577075, 4079.4900637420324, 4090.550039495668, 4129.380592911058, 4131.7894926214485, 4137.731047481919, 4098.9590104099025, 4126.546079931205, 4139.441175441307, 4142.0, 4147.979260103711, 3414.4878268259713, 3519.260955199411, 3559.7929283844182, 3585.8565657373674, 3595.7482976191677, 5130.333056702605, 5156.407013193456, 5156.576409087723, 5163.509466600667, 5167.010700880188, 4697.342372067913, 4697.741624210667, 4707.529247075299, 4711.044916359707, 4711.269753188261, 5407.513323800877, 5461.590380721422, 5462.180672899057, 5462.754176062087, 5479.589030822664, 3893.80530973453, 3906.902327441808, 3912.4522566840665, 3927.155661532784, 3932.3315036443873, 5529.815722789475, 5560.388357280696, 5705.400932902027, 5731.656100633975, 5740.799072032459, 4023.8189281482405, 4026.255142798592, 4027.597240275949, 4028.939565906514, 4058.817353219112, 4080.6327430531396, 4105.7331273467125, 4107.158032603309, 4124.628783409507, 4128.215639028635, 4548.590626843534, 4707.435107787019, 4711.976440117777, 4714.127886341038, 4722.574968252874, 5488.56091512678, 5505.284313039359, 5509.917351239735, 5567.860803479945, 5573.191887176336, 1398.8179987910403, 1420.8579142085916, 1425.9714805703827, 1432.5630682641895, 1436.1096120405207, 874.4665753890122, 909.6134142989204, 918.4305730447165, 935.6257497001254, 939.4598106088889, 4760.285957106462, 4763.356946812205, 4776.223776223776, 4787.0904528139545, 4794.712317260975, 4999.800007999712, 4999.875003124878, 5016.570277470903, 5074.264231686385, 5082.771275292621, 4777.727256829083, 4784.0, 4791.658335666019, 4795.657215979531, 5052.216906379517, 3012.7288544031144, 3039.7112274333904, 3055.8624861881267, 3058.495348267511, 3061.678523754974, 5886.735096920649, 5890.646561206341, 5903.557233207526, 5937.495312898365, 5941.168236446901, 5565.888682226333, 5781.739821708034, 5791.305043394819, 5804.564657650693, 5854.15594087861, 5821.854453638608, 5830.5627077968475, 5866.413358664099, 5869.413058694096, 5884.440978107073, 5431.511163995259, 5682.295311406644, 5684.602077854676, 5702.545850808017, 5711.742971566373, 5643.576731745054, 5672.602917795714, 5684.527230654513, 5694.184896119693, 5696.1455781633085, 5636.154576813511, 5744.310682718101, 5900.11498275245, 5907.881842363215, 5940.851478713067, 4083.2037752637953, 4104.548499664975, 4131.26486875507, 4132.504099507958, 4133.586641335963, 5548.889022219614, 5750.0, 5770.221020162392, 5787.971060144672, 5794.971025144847, 5525.696086715108, 5537.5569954402745, 5543.695096769555, 5545.944540554543, 5575.498205161555, 5482.5613950883035, 5492.615516913937, 5508.7521061553125, 5512.80705175317, 5540.36285827135, 5230.5815534757885, 5430.864228394164, 5441.700706461183, 5475.75359108849, 5508.283923089973, 5591.161325801162, 5597.328320601554, 5600.35595906476, 5611.270534830446, 5629.859253518531, 5422.5119739223655, 5467.234587157722, 5504.256925315031, 5529.889402212014, 5531.695756733418], "value": 4189.039647241478, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.4399810137647, 786.9645865936034, 831.8211584509331, 846.8264005878796, 857.5926434943399, 15291.847081529182, 16690.83309166908, 16727.66544669106, 16733.247003884822, 17409.390671326506, 16833.66332673346, 16853.494395168134, 16894.915525422388, 17005.40481083159, 17025.233864476097, 676.7834293026234, 809.7530253272749, 841.4908980067063, 844.3245403677065, 845.7800971747359, 793.6150966781136, 810.8256724804166, 818.071488860145, 824.9793755156139, 826.3224156191925, 867.3278209387744, 872.4111224923183, 873.9213470787628, 874.654511467973, 874.846901792187, 689.5621280486877, 836.5022811427191, 863.6329559937033, 866.5970323799432, 866.8959724833029, 631.1637080867822, 632.7057918068075, 632.7089538812152, 633.4488994574704, 633.8161933039413, 16190.0, 16242.187890605423, 16255.105969171691, 16261.83738162615, 16269.593260168473, 14616.707665846623, 14927.40290388383, 15049.774253386207, 15216.239188040554, 15274.541763747106, 13630.0, 13727.245001524912, 13778.931105344409, 13855.861441385558, 13882.514112006133, 4559.088182363541, 4636.907261854744, 4659.277811939155, 4675.696079754839, 4735.692180008288, 87893.99999999936, 88648.55675721644, 89071.55464222637, 90311.0, 90709.0, 11644.141240864996, 11653.59212427578, 11849.289042657469, 11876.88123118775, 12203.69490762738, 21755.782442175692, 21957.451063723536, 21965.0, 21970.231041913456, 21980.230691925706, 8811.515366654896, 8869.423487473294, 8899.0, 8906.59920303588, 9038.186563209343, 4960.553550180429, 4962.15643340633, 4987.351644286292, 5001.974990125026, 5224.0, 3475.3570589441033, 3542.929141417157, 3568.553930758655, 3576.0344706929504, 3585.964140358563, 602.4155975191115, 614.8800983808204, 619.4239357397663, 628.8773689130667, 638.7476946606065, 571.8191933657068, 650.443870490733, 723.2586598736232, 725.9673314700851, 742.417202496038, 9885.654002109892, 10022.74943126413, 10359.844602330972, 10409.791804164026, 10657.84013239802, 532.6271609873104, 600.9519238460913, 721.743780957763, 728.6479422625732, 731.5062332925254, 4727.621790256771, 4768.0, 4883.438404583448, 4986.526280003394, 4987.276844857475, 128595.35702321616, 130557.34721326522, 132856.67143328636, 133082.33458832643, 133313.0, 114400.0, 116622.0, 117946.41026794811, 117998.0, 118372.40813795876, 98113.0, 99767.0, 102284.0, 102717.0, 103129.48435257775, 9284.58219380116, 9348.298877584344, 9376.29677774156, 9406.43561386319, 9605.463125899823, 57831.71084144552, 58808.705956469945, 59582.40417595942, 60043.39956600553, 60881.39118608934, 82997.17002830136, 83432.58283708543, 83907.0, 86537.56731216304, 86854.0, 11971.880281197076, 12024.579139730417, 12108.636740897788, 12247.391952309985, 12384.75230495367, 13709.931450343083, 13812.0, 13902.0, 13971.860281397056, 14341.56975290743, 12500.24998500093, 12719.80920286178, 12840.61478155657, 13425.530106446618, 13516.729665406834, 12382.5666101686, 12727.172733772275, 12852.228866268053, 13078.803817942548, 13592.728145436839, 12326.876731232931, 12577.056720745797, 12675.93662031684, 12974.610761677164, 13029.543965961146, 10579.682609521727, 10639.414832184304, 11032.558697652004, 11142.562024512003, 11296.0, 10812.791538332229, 11245.887541124484, 11275.774484510428, 11278.774424511628, 11292.548298068314], "value": 7874.174165015995, "unit": "score"}], "name": "dromaeo_css", "value": 7367.610851356387, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 110.0, 112.0, 111.0, 110.0, 110.0, 109.0, 111.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 118.0, 174.0, 118.0, 118.0, 151.0, 123.0, 164.0, 116.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 121.0, 76.0, 75.0, 75.0, 75.0, 98.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 84.0, 85.0, 84.0, 119.0, 84.0, 85.0, 127.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 151.0, 148.0, 151.0, 151.0, 153.0, 153.0, 150.0, 147.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 54.0, 53.0, 56.0, 55.0, 53.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 78.0, 80.0, 81.0, 75.0, 77.0, 76.0, 75.0, 75.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 119.0, 119.0, 126.0, 162.0, 128.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 155.0, 157.0, 152.0, 157.0, 156.0, 152.0, 155.0, 155.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 60.0, 62.0, 65.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1478.7000000000003, "alertThreshold": 2.0}]} 03:35:53 INFO - Return code: 0 03:35:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:35:53 INFO - # TBPL SUCCESS # 03:35:53 INFO - Running post-action listener: _resource_record_post_action 03:35:53 INFO - ##### 03:35:53 INFO - ##### Finished run-tests step (success) 03:35:53 INFO - ##### 03:35:53 INFO - Running post-run listener: _resource_record_post_run 03:35:53 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 12288; Write bytes: 553435136; Read time: 20; Write time: 463248 03:35:53 INFO - TinderboxPrint: CPU usage
13.0% 03:35:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 20 03:35:53 INFO - TinderboxPrint: I/O write bytes / time
553,435,136 / 463,248 03:35:53 INFO - TinderboxPrint: CPU idle
8,269.2 (87.0%) 03:35:53 INFO - TinderboxPrint: CPU user
1,195.2 (12.6%) 03:35:53 INFO - TinderboxPrint: Swap in / out
0 / 0 03:35:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256520192; Read time: 0; Write time: 292008 03:35:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296673280; Read time: 8; Write time: 171216 03:35:54 INFO - Running post-run listener: _upload_blobber_files 03:35:54 INFO - Blob upload gear active. 03:35:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:35:54 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 03:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:35:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:58 INFO - (blobuploader) - INFO - Done attempting. 03:35:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:35:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:58 INFO - (blobuploader) - INFO - Done attempting. 03:35:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:35:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:35:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:59 INFO - (blobuploader) - INFO - Done attempting. 03:35:59 INFO - (blobuploader) - INFO - Iteration through files over. 03:35:59 INFO - Return code: 0 03:35:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:35:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:35:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64ac14ed657ce6c45c04f52d3d1f2caba54ac72dbeaa2a4ab645944406ebae01b167f0f9a2926e42e9d459b4fc19cde35582c994dc19c0696bdf040b68663478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491149e1886a2b11a818438291eec7a71a0ad8485c0940fd03ad2e524dd3f5eb825ee8679881bbfb1fe47568c989865b633b7aeee16ddd3b32ca524438bafc0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d254624a9c4f59e6eac9a51b18992b5b37c2aa61f9c1de7c14e3d8f915d232cc3f97848cff1c91f903b27112551ea75db5909e8dd0388f74d5d654f9b77e95d6"} 03:35:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:35:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:35:59 INFO - Contents: 03:35:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64ac14ed657ce6c45c04f52d3d1f2caba54ac72dbeaa2a4ab645944406ebae01b167f0f9a2926e42e9d459b4fc19cde35582c994dc19c0696bdf040b68663478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491149e1886a2b11a818438291eec7a71a0ad8485c0940fd03ad2e524dd3f5eb825ee8679881bbfb1fe47568c989865b633b7aeee16ddd3b32ca524438bafc0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d254624a9c4f59e6eac9a51b18992b5b37c2aa61f9c1de7c14e3d8f915d232cc3f97848cff1c91f903b27112551ea75db5909e8dd0388f74d5d654f9b77e95d6"} 03:35:59 INFO - Running post-run listener: copy_logs_to_upload_dir 03:35:59 INFO - Copying logs to upload dir... 03:35:59 INFO - mkdir: /builds/slave/test/build/upload/logs 03:35:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.982404 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-09 03:35:59.624802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:35:59.629683) ========= 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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64ac14ed657ce6c45c04f52d3d1f2caba54ac72dbeaa2a4ab645944406ebae01b167f0f9a2926e42e9d459b4fc19cde35582c994dc19c0696bdf040b68663478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491149e1886a2b11a818438291eec7a71a0ad8485c0940fd03ad2e524dd3f5eb825ee8679881bbfb1fe47568c989865b633b7aeee16ddd3b32ca524438bafc0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d254624a9c4f59e6eac9a51b18992b5b37c2aa61f9c1de7c14e3d8f915d232cc3f97848cff1c91f903b27112551ea75db5909e8dd0388f74d5d654f9b77e95d6"} build_url:https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64ac14ed657ce6c45c04f52d3d1f2caba54ac72dbeaa2a4ab645944406ebae01b167f0f9a2926e42e9d459b4fc19cde35582c994dc19c0696bdf040b68663478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491149e1886a2b11a818438291eec7a71a0ad8485c0940fd03ad2e524dd3f5eb825ee8679881bbfb1fe47568c989865b633b7aeee16ddd3b32ca524438bafc0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d254624a9c4f59e6eac9a51b18992b5b37c2aa61f9c1de7c14e3d8f915d232cc3f97848cff1c91f903b27112551ea75db5909e8dd0388f74d5d654f9b77e95d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:35:59.657536) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:35:59.657878) ========= 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=523ca41ca1e0623de333ad52000001bf-1468056580.328508-663325195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:35:59.707086) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:35:59.710443) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:35:59.710748) ========= ========= Total master_lag: 0.66 =========