builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1468059873.25 results: success (0) buildid: 20160709003001 builduid: a1824a7c5b154dd284533ec0b0edabdc revision: e3dcf062007e280ccf06e7bed7ff904d0fb44418 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.254257) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.254685) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.255046) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.291297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.291582) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-09 03:24:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-07-09 03:24:33 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119489 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.425524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.425853) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017653 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.475677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:33.476020) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-09 03:24:34,022 truncating revision to first 12 chars 2016-07-09 03:24:34,022 Setting DEBUG logging. 2016-07-09 03:24:34,022 attempt 1/10 2016-07-09 03:24:34,022 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:24:34,323 unpacking tar archive at: autoland-e3dcf062007e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.633194 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:34.130124) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:34.130456) ========= script_repo_revision: e3dcf062007e280ccf06e7bed7ff904d0fb44418 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:34.130938) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:34.131317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 03:24:34.147920) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-09 03:24:34.148286) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:24:34 INFO - MultiFileLogger online at 20160709 03:24:34 in /builds/slave/test 03:24:34 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:24:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:24:34 INFO - {'append_to_log': False, 03:24:34 INFO - 'base_work_dir': '/builds/slave/test', 03:24:34 INFO - 'blob_upload_branch': 'Autoland', 03:24:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:24:34 INFO - 'branch': 'Autoland', 03:24:34 INFO - 'buildbot_json_path': 'buildprops.json', 03:24:34 INFO - 'config_files': ('talos/linux_config.py',), 03:24:34 INFO - 'default_actions': ('clobber', 03:24:34 INFO - 'read-buildbot-config', 03:24:34 INFO - 'download-and-extract', 03:24:34 INFO - 'populate-webroot', 03:24:34 INFO - 'create-virtualenv', 03:24:34 INFO - 'install', 03:24:34 INFO - 'run-tests'), 03:24:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:24:34 INFO - 'download_minidump_stackwalk': True, 03:24:34 INFO - 'download_symbols': 'ondemand', 03:24:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:24:34 INFO - 'tooltool.py': '/tools/tooltool.py', 03:24:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:24:34 INFO - '/tools/misc-python/virtualenv.py')}, 03:24:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:24:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:24:34 INFO - 'installer_path': 'installer.exe', 03:24:34 INFO - 'log_level': 'info', 03:24:34 INFO - 'log_name': 'talos', 03:24:34 INFO - 'log_to_console': True, 03:24:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:24:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:24:34 INFO - 'opt_config_files': (), 03:24:34 INFO - 'pip_index': False, 03:24:34 INFO - 'sps_profile': False, 03:24:34 INFO - 'sps_profile_interval': 0, 03:24:34 INFO - 'suite': 'dromaeojs', 03:24:34 INFO - 'system_bits': '32', 03:24:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:24:34 INFO - 'title': 'talos-linux64-ix-012', 03:24:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:24:34 INFO - 'use_talos_json': True, 03:24:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:24:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:24:34 INFO - 'work_dir': 'build'} 03:24:34 INFO - ##### 03:24:34 INFO - ##### Running clobber step. 03:24:34 INFO - ##### 03:24:34 INFO - Running pre-action listener: _resource_record_pre_action 03:24:34 INFO - Running main action method: clobber 03:24:34 INFO - rmtree: /builds/slave/test/build 03:24:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:24:35 INFO - Running post-action listener: _resource_record_post_action 03:24:35 INFO - ##### 03:24:35 INFO - ##### Finished clobber step (success) 03:24:35 INFO - ##### 03:24:35 INFO - ##### 03:24:35 INFO - ##### Running read-buildbot-config step. 03:24:35 INFO - ##### 03:24:35 INFO - Running pre-action listener: _resource_record_pre_action 03:24:35 INFO - Running main action method: read_buildbot_config 03:24:35 INFO - Using buildbot properties: 03:24:35 INFO - { 03:24:35 INFO - "project": "", 03:24:35 INFO - "product": "firefox", 03:24:35 INFO - "script_repo_revision": "production", 03:24:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 03:24:35 INFO - "repository": "", 03:24:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 03:24:35 INFO - "buildid": "20160709003001", 03:24:35 INFO - "pgo_build": "True", 03:24:35 INFO - "basedir": "/builds/slave/test", 03:24:35 INFO - "buildnumber": 14, 03:24:35 INFO - "slavename": "talos-linux64-ix-012", 03:24:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:24:35 INFO - "platform": "ubuntu64_hw", 03:24:35 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 03:24:35 INFO - "branch": "autoland", 03:24:35 INFO - "repo_path": "integration/autoland", 03:24:35 INFO - "revision": "e3dcf062007e280ccf06e7bed7ff904d0fb44418", 03:24:35 INFO - "stage_platform": "linux64-pgo", 03:24:35 INFO - "builduid": "a1824a7c5b154dd284533ec0b0edabdc", 03:24:35 INFO - "slavebuilddir": "test" 03:24:35 INFO - } 03:24:35 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:24:35 INFO - Running post-action listener: _resource_record_post_action 03:24:35 INFO - ##### 03:24:35 INFO - ##### Finished read-buildbot-config step (success) 03:24:35 INFO - ##### 03:24:35 INFO - ##### 03:24:35 INFO - ##### Running download-and-extract step. 03:24:35 INFO - ##### 03:24:35 INFO - Running pre-action listener: _resource_record_pre_action 03:24:35 INFO - Running main action method: download_and_extract 03:24:35 INFO - mkdir: /builds/slave/test/build/tests 03:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:35 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:24:35 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:24:35 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:24:35 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:24:35 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:24:41 INFO - Downloaded 1361 bytes. 03:24:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:24:41 INFO - Using the following test package requirements: 03:24:41 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:24:41 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:24:41 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:24:41 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'jsshell-linux-x86_64.zip'], 03:24:41 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:24:41 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:24:41 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:24:41 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:24:41 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:24:41 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:24:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:24:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:24:41 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:24:41 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:24:41 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:24:41 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:24:41 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:24:41 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:24:42 INFO - Downloaded 24303043 bytes. 03:24:42 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:24:42 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:24:43 INFO - Return code: 0 03:24:43 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:24: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:24:43 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:24:43 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:24:43 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:24:43 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:24:43 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:24:44 INFO - Downloaded 24303043 bytes. 03:24:44 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:24:44 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:24:46 INFO - Return code: 0 03:24:46 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:24:46 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:24:46 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:24:46 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:24:46 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:24:46 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:24:47 INFO - Downloaded 14403333 bytes. 03:24:47 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:24:47 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:24:47 INFO - Return code: 0 03:24:47 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:24:47 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:24:47 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:24:47 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:24:47 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:24:47 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:24:48 INFO - Downloaded 60420567 bytes. 03:24:48 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:24:48 INFO - mkdir: /builds/slave/test/properties 03:24:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:24:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:24:48 INFO - Contents: 03:24:48 INFO - build_url:https://queue.taskcluster.net/v1/task/IbHkw6BCSwOKmP8VM1dq8g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:24:49 INFO - Running post-action listener: _resource_record_post_action 03:24:49 INFO - Running post-action listener: set_extra_try_arguments 03:24:49 INFO - ##### 03:24:49 INFO - ##### Finished download-and-extract step (success) 03:24:49 INFO - ##### 03:24:49 INFO - ##### 03:24:49 INFO - ##### Running populate-webroot step. 03:24:49 INFO - ##### 03:24:49 INFO - Running pre-action listener: _resource_record_pre_action 03:24:49 INFO - Running main action method: populate_webroot 03:24:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:24:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:24:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:24:49 INFO - '--tppagecycles', 03:24:49 INFO - '7'], 03:24:49 INFO - 'tests': ['tsvgm']}}, 03:24:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:24:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:24:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:24:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:24:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['damp', 'tps']}, 03:24:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['damp', 'tps']}, 03:24:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['dromaeo_dom']}, 03:24:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:24:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['basic_compositor_video']}, 03:24:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:24:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tsvgx', 03:24:49 INFO - 'tsvgr_opacity', 03:24:49 INFO - 'tart', 03:24:49 INFO - 'tscrollx', 03:24:49 INFO - 'cart']}, 03:24:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:24:49 INFO - 'tsvgr_opacity', 03:24:49 INFO - 'tart', 03:24:49 INFO - 'tscrollx', 03:24:49 INFO - 'cart']}, 03:24:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tp5o']}, 03:24:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['tp5o']}, 03:24:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s', 03:24:49 INFO - '--xperf_path', 03:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:49 INFO - 'tests': ['tp5n']}, 03:24:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--xperf_path', 03:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:49 INFO - 'tests': ['tp5n']}}, 03:24:49 INFO - 'talos.zip': {'path': '', 03:24:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:24:49 INFO - Running post-action listener: _resource_record_post_action 03:24:49 INFO - ##### 03:24:49 INFO - ##### Finished populate-webroot step (success) 03:24:49 INFO - ##### 03:24:49 INFO - ##### 03:24:49 INFO - ##### Running create-virtualenv step. 03:24:49 INFO - ##### 03:24:49 INFO - Running pre-action listener: _resource_record_pre_action 03:24:49 INFO - Running main action method: create_virtualenv 03:24:49 INFO - Creating virtualenv /builds/slave/test/build/venv 03:24:49 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:24:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:24:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:24:49 INFO - Using real prefix '/usr' 03:24:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:24:50 INFO - Installing distribute.............................................................................................................................................................................................done. 03:24:52 INFO - Installing pip.................done. 03:24:52 INFO - Return code: 0 03:24:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:24:52 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:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 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:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:24:52 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:24:52 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:24:52 INFO - Using env: {'DISPLAY': ':0', 03:24:52 INFO - 'HOME': '/home/cltbld', 03:24:52 INFO - 'LANG': 'en_US.UTF-8', 03:24:52 INFO - 'LANGUAGE': 'en_US:en', 03:24:52 INFO - 'LOGNAME': 'cltbld', 03:24:52 INFO - 'MAIL': '/var/mail/cltbld', 03:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:52 INFO - 'MOZ_NO_REMOTE': '1', 03:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:52 INFO - 'NO_EM_RESTART': '1', 03:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:52 INFO - 'PWD': '/builds/slave/test', 03:24:52 INFO - 'SHELL': '/bin/bash', 03:24:52 INFO - 'SHLVL': '1', 03:24:52 INFO - 'TERM': 'linux', 03:24:52 INFO - 'TMOUT': '86400', 03:24:52 INFO - 'USER': 'cltbld', 03:24:52 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:52 INFO - '_': '/tools/buildbot/bin/python'} 03:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:52 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:24:52 INFO - Cleaning up... 03:24:52 INFO - Return code: 0 03:24:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:24:52 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:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 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:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:24:52 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:24:52 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:24:52 INFO - Using env: {'DISPLAY': ':0', 03:24:52 INFO - 'HOME': '/home/cltbld', 03:24:52 INFO - 'LANG': 'en_US.UTF-8', 03:24:52 INFO - 'LANGUAGE': 'en_US:en', 03:24:52 INFO - 'LOGNAME': 'cltbld', 03:24:52 INFO - 'MAIL': '/var/mail/cltbld', 03:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:52 INFO - 'MOZ_NO_REMOTE': '1', 03:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:52 INFO - 'NO_EM_RESTART': '1', 03:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:52 INFO - 'PWD': '/builds/slave/test', 03:24:52 INFO - 'SHELL': '/bin/bash', 03:24:52 INFO - 'SHLVL': '1', 03:24:52 INFO - 'TERM': 'linux', 03:24:52 INFO - 'TMOUT': '86400', 03:24:52 INFO - 'USER': 'cltbld', 03:24:52 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:52 INFO - '_': '/tools/buildbot/bin/python'} 03:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:52 INFO - Downloading/unpacking psutil>=3.1.1 03:24: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:24: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:24: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:24: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:24: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:24: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:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:24:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:54 INFO - Installing collected packages: psutil 03:24:54 INFO - Running setup.py install for psutil 03:24:54 INFO - building 'psutil._psutil_linux' extension 03:24:54 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:24:54 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:24:54 INFO - building 'psutil._psutil_posix' extension 03:24:54 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:24:54 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:24:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:54 INFO - Successfully installed psutil 03:24:54 INFO - Cleaning up... 03:24:55 INFO - Return code: 0 03:24:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:24:55 INFO - Using env: {'DISPLAY': ':0', 03:24:55 INFO - 'HOME': '/home/cltbld', 03:24:55 INFO - 'LANG': 'en_US.UTF-8', 03:24:55 INFO - 'LANGUAGE': 'en_US:en', 03:24:55 INFO - 'LOGNAME': 'cltbld', 03:24:55 INFO - 'MAIL': '/var/mail/cltbld', 03:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:55 INFO - 'MOZ_NO_REMOTE': '1', 03:24:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:55 INFO - 'NO_EM_RESTART': '1', 03:24:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:55 INFO - 'PWD': '/builds/slave/test', 03:24:55 INFO - 'SHELL': '/bin/bash', 03:24:55 INFO - 'SHLVL': '1', 03:24:55 INFO - 'TERM': 'linux', 03:24:55 INFO - 'TMOUT': '86400', 03:24:55 INFO - 'USER': 'cltbld', 03:24:55 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:55 INFO - '_': '/tools/buildbot/bin/python'} 03:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:24:55 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:24:55 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:24:55 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:24:55 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:24:55 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:24:55 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:24:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:24:57 INFO - Installing collected packages: mozsystemmonitor 03:24:57 INFO - Running setup.py install for mozsystemmonitor 03:24:57 INFO - Successfully installed mozsystemmonitor 03:24:57 INFO - Cleaning up... 03:24:57 INFO - Return code: 0 03:24:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:24: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:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24: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:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:57 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:24:57 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:24:57 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:24:57 INFO - Using env: {'DISPLAY': ':0', 03:24:57 INFO - 'HOME': '/home/cltbld', 03:24:57 INFO - 'LANG': 'en_US.UTF-8', 03:24:57 INFO - 'LANGUAGE': 'en_US:en', 03:24:57 INFO - 'LOGNAME': 'cltbld', 03:24:57 INFO - 'MAIL': '/var/mail/cltbld', 03:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:57 INFO - 'MOZ_NO_REMOTE': '1', 03:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:57 INFO - 'NO_EM_RESTART': '1', 03:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:57 INFO - 'PWD': '/builds/slave/test', 03:24:57 INFO - 'SHELL': '/bin/bash', 03:24:57 INFO - 'SHLVL': '1', 03:24:57 INFO - 'TERM': 'linux', 03:24:57 INFO - 'TMOUT': '86400', 03:24:57 INFO - 'USER': 'cltbld', 03:24:57 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:57 INFO - '_': '/tools/buildbot/bin/python'} 03:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:57 INFO - Downloading/unpacking jsonschema==2.5.1 03:24:57 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:24:57 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:24:57 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:24:57 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:24:57 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:24:57 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:24:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:24:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:24:59 INFO - warning: no files found matching '*.txt' 03:24:59 INFO - no previously-included directories found matching 'build' 03:24:59 INFO - no previously-included directories found matching 'dist' 03:24:59 INFO - no previously-included directories found matching '.git*' 03:24:59 INFO - Installing collected packages: jsonschema, functools32 03:24:59 INFO - Running setup.py install for functools32 03:24:59 INFO - warning: no files found matching '*.txt' 03:24:59 INFO - no previously-included directories found matching 'build' 03:24:59 INFO - no previously-included directories found matching 'dist' 03:24:59 INFO - no previously-included directories found matching '.git*' 03:24:59 INFO - Successfully installed jsonschema functools32 03:24:59 INFO - Cleaning up... 03:24:59 INFO - Return code: 0 03:24:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:24:59 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:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:59 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:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:59 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:24:59 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:24:59 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:24:59 INFO - Using env: {'DISPLAY': ':0', 03:24:59 INFO - 'HOME': '/home/cltbld', 03:24:59 INFO - 'LANG': 'en_US.UTF-8', 03:24:59 INFO - 'LANGUAGE': 'en_US:en', 03:24:59 INFO - 'LOGNAME': 'cltbld', 03:24:59 INFO - 'MAIL': '/var/mail/cltbld', 03:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:59 INFO - 'MOZ_NO_REMOTE': '1', 03:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:59 INFO - 'NO_EM_RESTART': '1', 03:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:59 INFO - 'PWD': '/builds/slave/test', 03:24:59 INFO - 'SHELL': '/bin/bash', 03:24:59 INFO - 'SHLVL': '1', 03:24:59 INFO - 'TERM': 'linux', 03:24:59 INFO - 'TMOUT': '86400', 03:24:59 INFO - 'USER': 'cltbld', 03:24:59 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:59 INFO - '_': '/tools/buildbot/bin/python'} 03:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:25:00 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:25:00 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:25:00 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:25:00 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:25:00 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:25:00 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:25:01 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 INFO - Downloading docopt-0.6.1.tar.gz 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:02 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:02 INFO - Running setup.py install for blobuploader 03:25:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:03 INFO - Running setup.py install for requests 03:25:03 INFO - Running setup.py install for docopt 03:25:03 INFO - Successfully installed blobuploader requests docopt 03:25:03 INFO - Cleaning up... 03:25:03 INFO - Return code: 0 03:25:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:25:03 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:25:03 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:25:03 INFO - Using env: {'DISPLAY': ':0', 03:25:03 INFO - 'HOME': '/home/cltbld', 03:25:03 INFO - 'LANG': 'en_US.UTF-8', 03:25:03 INFO - 'LANGUAGE': 'en_US:en', 03:25:03 INFO - 'LOGNAME': 'cltbld', 03:25:03 INFO - 'MAIL': '/var/mail/cltbld', 03:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:03 INFO - 'MOZ_NO_REMOTE': '1', 03:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:03 INFO - 'NO_EM_RESTART': '1', 03:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:03 INFO - 'PWD': '/builds/slave/test', 03:25:03 INFO - 'SHELL': '/bin/bash', 03:25:03 INFO - 'SHLVL': '1', 03:25:03 INFO - 'TERM': 'linux', 03:25:03 INFO - 'TMOUT': '86400', 03:25:03 INFO - 'USER': 'cltbld', 03:25:03 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:03 INFO - '_': '/tools/buildbot/bin/python'} 03:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:03 INFO - Running setup.py (path:/tmp/pip-jlrhfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:03 INFO - Running setup.py (path:/tmp/pip-gWkJ5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:03 INFO - Running setup.py (path:/tmp/pip-phrjIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:04 INFO - Running setup.py (path:/tmp/pip-BylvzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:04 INFO - Running setup.py (path:/tmp/pip-ojCf8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:04 INFO - Running setup.py (path:/tmp/pip-nkijwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:04 INFO - Running setup.py (path:/tmp/pip-fkq02d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:04 INFO - Running setup.py (path:/tmp/pip-IEEnbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:04 INFO - Running setup.py (path:/tmp/pip-A0nvGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:04 INFO - Running setup.py (path:/tmp/pip-WzRz_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:04 INFO - Running setup.py (path:/tmp/pip-EE924I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:04 INFO - Running setup.py (path:/tmp/pip-Y84Pf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:04 INFO - Running setup.py (path:/tmp/pip-oeL0_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:04 INFO - Running setup.py (path:/tmp/pip-UsdZMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:04 INFO - Running setup.py (path:/tmp/pip-D3Dfcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:04 INFO - Running setup.py (path:/tmp/pip-wYlFls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:04 INFO - Running setup.py (path:/tmp/pip-H5PDsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:04 INFO - Running setup.py install for manifestparser 03:25:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozcrash 03:25:05 INFO - Running setup.py install for mozdebug 03:25:05 INFO - Running setup.py install for mozdevice 03:25:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozfile 03:25:05 INFO - Running setup.py install for mozhttpd 03:25:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozinfo 03:25:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozInstall 03:25:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozleak 03:25:05 INFO - Running setup.py install for mozlog 03:25:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for moznetwork 03:25:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozprocess 03:25:06 INFO - Running setup.py install for mozprofile 03:25:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozrunner 03:25:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozscreenshot 03:25:06 INFO - Running setup.py install for moztest 03:25:06 INFO - Running setup.py install for mozversion 03:25:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:25:06 INFO - Cleaning up... 03:25:06 INFO - Return code: 0 03:25:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:06 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:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:06 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:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:06 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:25:06 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:25:06 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:25:06 INFO - Using env: {'DISPLAY': ':0', 03:25:06 INFO - 'HOME': '/home/cltbld', 03:25:06 INFO - 'LANG': 'en_US.UTF-8', 03:25:06 INFO - 'LANGUAGE': 'en_US:en', 03:25:06 INFO - 'LOGNAME': 'cltbld', 03:25:06 INFO - 'MAIL': '/var/mail/cltbld', 03:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:06 INFO - 'MOZ_NO_REMOTE': '1', 03:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:06 INFO - 'NO_EM_RESTART': '1', 03:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:06 INFO - 'PWD': '/builds/slave/test', 03:25:06 INFO - 'SHELL': '/bin/bash', 03:25:06 INFO - 'SHLVL': '1', 03:25:06 INFO - 'TERM': 'linux', 03:25:06 INFO - 'TMOUT': '86400', 03:25:06 INFO - 'USER': 'cltbld', 03:25:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:06 INFO - '_': '/tools/buildbot/bin/python'} 03:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:07 INFO - Running setup.py (path:/tmp/pip-6txZBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:07 INFO - Running setup.py (path:/tmp/pip-sjxSny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:07 INFO - Running setup.py (path:/tmp/pip-kWb7tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:07 INFO - Running setup.py (path:/tmp/pip-CiWBI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:07 INFO - Running setup.py (path:/tmp/pip-UXR8lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:07 INFO - Running setup.py (path:/tmp/pip-GvT09J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:07 INFO - Running setup.py (path:/tmp/pip-hLoIDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:07 INFO - Running setup.py (path:/tmp/pip-dvQRCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:07 INFO - Running setup.py (path:/tmp/pip-tEH5zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:07 INFO - Running setup.py (path:/tmp/pip-xmCmpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:07 INFO - Running setup.py (path:/tmp/pip-FxqvaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:07 INFO - Running setup.py (path:/tmp/pip-n0fJpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:07 INFO - Running setup.py (path:/tmp/pip-VAe0rU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:07 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:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:07 INFO - Running setup.py (path:/tmp/pip-EtAqj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:08 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:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:08 INFO - Running setup.py (path:/tmp/pip-msQPUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:08 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:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:08 INFO - Running setup.py (path:/tmp/pip-fiJGhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:08 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:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:08 INFO - Running setup.py (path:/tmp/pip-bGyVoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:10 INFO - Downloading blessings-1.6.tar.gz 03:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:25:10 INFO - Installing collected packages: blessings 03:25:10 INFO - Running setup.py install for blessings 03:25:10 INFO - Successfully installed blessings 03:25:10 INFO - Cleaning up... 03:25:10 INFO - Return code: 0 03:25:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:10 INFO - Reading from file tmpfile_stdout 03:25:10 INFO - Current package versions: 03:25:10 INFO - argparse == 1.2.1 03:25:10 INFO - blessings == 1.6 03:25:10 INFO - blobuploader == 1.2.4 03:25:10 INFO - docopt == 0.6.1 03:25:10 INFO - functools32 == 3.2.3-2 03:25:10 INFO - jsonschema == 2.5.1 03:25:10 INFO - manifestparser == 1.1 03:25:10 INFO - mozInstall == 1.12 03:25:10 INFO - mozcrash == 0.17 03:25:10 INFO - mozdebug == 0.1 03:25:10 INFO - mozdevice == 0.48 03:25:10 INFO - mozfile == 1.2 03:25:10 INFO - mozhttpd == 0.7 03:25:10 INFO - mozinfo == 0.9 03:25:10 INFO - mozleak == 0.1 03:25:10 INFO - mozlog == 3.2 03:25:10 INFO - moznetwork == 0.27 03:25:10 INFO - mozprocess == 0.23 03:25:10 INFO - mozprofile == 0.28 03:25:10 INFO - mozrunner == 6.12 03:25:10 INFO - mozscreenshot == 0.1 03:25:10 INFO - mozsystemmonitor == 0.3 03:25:10 INFO - moztest == 0.7 03:25:10 INFO - mozversion == 1.4 03:25:10 INFO - psutil == 3.1.1 03:25:10 INFO - requests == 1.2.3 03:25:10 INFO - wsgiref == 0.1.2 03:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:25:10 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:25:10 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:25:10 INFO - Using env: {'DISPLAY': ':0', 03:25:10 INFO - 'HOME': '/home/cltbld', 03:25:10 INFO - 'LANG': 'en_US.UTF-8', 03:25:10 INFO - 'LANGUAGE': 'en_US:en', 03:25:10 INFO - 'LOGNAME': 'cltbld', 03:25:10 INFO - 'MAIL': '/var/mail/cltbld', 03:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:10 INFO - 'MOZ_NO_REMOTE': '1', 03:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:10 INFO - 'NO_EM_RESTART': '1', 03:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:10 INFO - 'PWD': '/builds/slave/test', 03:25:10 INFO - 'SHELL': '/bin/bash', 03:25:10 INFO - 'SHLVL': '1', 03:25:10 INFO - 'TERM': 'linux', 03:25:10 INFO - 'TMOUT': '86400', 03:25:10 INFO - 'USER': 'cltbld', 03:25:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:10 INFO - '_': '/tools/buildbot/bin/python'} 03:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 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:25:10 INFO - Cleaning up... 03:25:10 INFO - Return code: 0 03:25:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 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 0x1e5e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', '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:25:10 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:25:10 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:25:10 INFO - Using env: {'DISPLAY': ':0', 03:25:10 INFO - 'HOME': '/home/cltbld', 03:25:10 INFO - 'LANG': 'en_US.UTF-8', 03:25:10 INFO - 'LANGUAGE': 'en_US:en', 03:25:10 INFO - 'LOGNAME': 'cltbld', 03:25:10 INFO - 'MAIL': '/var/mail/cltbld', 03:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:10 INFO - 'MOZ_NO_REMOTE': '1', 03:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:10 INFO - 'NO_EM_RESTART': '1', 03:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:10 INFO - 'PWD': '/builds/slave/test', 03:25:10 INFO - 'SHELL': '/bin/bash', 03:25:10 INFO - 'SHLVL': '1', 03:25:10 INFO - 'TERM': 'linux', 03:25:10 INFO - 'TMOUT': '86400', 03:25:10 INFO - 'USER': 'cltbld', 03:25:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:10 INFO - '_': '/tools/buildbot/bin/python'} 03:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:25:10 INFO - Cleaning up... 03:25:11 INFO - Return code: 0 03:25:11 INFO - Running post-action listener: _resource_record_post_action 03:25:11 INFO - Running post-action listener: _start_resource_monitoring 03:25:11 INFO - Starting resource monitoring. 03:25:11 INFO - ##### 03:25:11 INFO - ##### Finished create-virtualenv step (success) 03:25:11 INFO - ##### 03:25:11 INFO - ##### 03:25:11 INFO - ##### Running install step. 03:25:11 INFO - ##### 03:25:11 INFO - Running pre-action listener: _resource_record_pre_action 03:25:11 INFO - Running main action method: install 03:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:11 INFO - Reading from file tmpfile_stdout 03:25:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:25:11 INFO - Reading from file tmpfile_stdout 03:25:11 INFO - Output received: 03:25:11 INFO - Usage: mozinstall [options] installer 03:25:11 INFO - Options: 03:25:11 INFO - -h, --help show this help message and exit 03:25:11 INFO - -d DEST, --destination=DEST 03:25:11 INFO - Directory to install application into. [default: 03:25:11 INFO - "/builds/slave/test"] 03:25:11 INFO - --app=APP Application being installed. [default: firefox] 03:25:11 INFO - mkdir: /builds/slave/test/build/application 03:25:11 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:25:11 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:25:28 INFO - Reading from file tmpfile_stdout 03:25:28 INFO - Output received: 03:25:28 INFO - /builds/slave/test/build/application/firefox/firefox 03:25:28 INFO - Running post-action listener: _resource_record_post_action 03:25:28 INFO - ##### 03:25:28 INFO - ##### Finished install step (success) 03:25:28 INFO - ##### 03:25:28 INFO - ##### 03:25:28 INFO - ##### Running run-tests step. 03:25:28 INFO - ##### 03:25:28 INFO - Running pre-action listener: _resource_record_pre_action 03:25:28 INFO - Running main action method: run_tests 03:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:25:28 INFO - Python 2.7.3 03:25:28 INFO - Return code: 0 03:25:28 INFO - grabbing minidump binary from tooltool 03:25: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:25:28 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 0x211d3b0>, '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 0x211e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x211e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:25:28 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:25:28 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:25:28 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:25:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:25:28 INFO - Return code: 0 03:25:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:25:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:25:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:25:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:25:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:25:28 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-012', '--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:25:28 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-012 --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:25:28 INFO - Using env: {'DISPLAY': ':0', 03:25:28 INFO - 'HOME': '/home/cltbld', 03:25:28 INFO - 'LANG': 'en_US.UTF-8', 03:25:28 INFO - 'LANGUAGE': 'en_US:en', 03:25:28 INFO - 'LOGNAME': 'cltbld', 03:25:28 INFO - 'MAIL': '/var/mail/cltbld', 03:25:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:25:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:28 INFO - 'MOZ_NO_REMOTE': '1', 03:25:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:28 INFO - 'NO_EM_RESTART': '1', 03:25:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:28 INFO - 'PWD': '/builds/slave/test', 03:25:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:25:28 INFO - 'SHELL': '/bin/bash', 03:25:28 INFO - 'SHLVL': '1', 03:25:28 INFO - 'TERM': 'linux', 03:25:28 INFO - 'TMOUT': '86400', 03:25:28 INFO - 'USER': 'cltbld', 03:25:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415', 03:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:28 INFO - '_': '/tools/buildbot/bin/python'} 03:25:28 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-012', '--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:25:28 INFO - mozversion application_buildid: 20160709003001 03:25:28 INFO - mozversion application_changeset: e3dcf062007e280ccf06e7bed7ff904d0fb44418 03:25:28 INFO - mozversion application_display_name: Nightly 03:25:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:25:28 INFO - mozversion application_name: Firefox 03:25:28 INFO - mozversion application_remotingname: firefox 03:25:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:25:28 INFO - mozversion application_vendor: Mozilla 03:25:28 INFO - mozversion application_version: 50.0a1 03:25:28 INFO - mozversion platform_buildid: 20160709003001 03:25:28 INFO - mozversion platform_changeset: e3dcf062007e280ccf06e7bed7ff904d0fb44418 03:25:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:25:28 INFO - mozversion platform_version: 50.0a1 03:25:28 INFO - using testdate: 1468059928 03:25:28 INFO - actual date: 1468059928 03:25:28 INFO - starting webserver on 'localhost:48095' 03:25:28 INFO - SUITE-START | Running 2 tests 03:25:28 INFO - TEST-START | dromaeo_css 03:25:28 INFO - operating with platform_type : linux_ 03:25:28 INFO - Initialising browser for dromaeo_css test... 03:25:28 INFO - TEST-INFO | started process 26576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBce6b4/profile http://localhost:48095/getInfo.html) 03:25:32 INFO - PROCESS | 26576 | __metrics Screen width/height:1600/1200 03:25:32 INFO - PROCESS | 26576 | colorDepth:24 03:25:32 INFO - PROCESS | 26576 | Browser inner width/height: 1024/697 03:25:32 INFO - PROCESS | 26576 | __metrics 03:25:33 INFO - PROCESS | 26576 | 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:25:33 INFO - PROCESS | 26576 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:25:33 INFO - TEST-INFO | 26576: exit 0 03:25:33 INFO - Browser initialized. 03:25:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:25:33 INFO - TEST-INFO | started process 26661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBce6b4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:45 INFO - PROCESS | 26661 | RSS: Main: 157184000 03:25:45 INFO - PROCESS | 26661 | 03:26:36 INFO - PROCESS | 26661 | 1468059996968 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:28:44 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48095/tests/dromaeo/cssquery-ext.html) 03:28:45 INFO - PROCESS | 26661 | RSS: Main: 259063808 03:28:45 INFO - PROCESS | 26661 | 03:31:47 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-ext.html (next: http://localhost:48095/tests/dromaeo/cssquery-jquery.html) 03:31:48 INFO - PROCESS | 26661 | RSS: Main: 242155520 03:31:48 INFO - PROCESS | 26661 | 03:34:50 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48095/tests/dromaeo/cssquery-mootools.html) 03:34:51 INFO - PROCESS | 26661 | RSS: Main: 235364352 03:34:51 INFO - PROCESS | 26661 | 03:37:53 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48095/tests/dromaeo/cssquery-prototype.html) 03:37:53 INFO - PROCESS | 26661 | RSS: Main: 236756992 03:37:53 INFO - PROCESS | 26661 | 03:40:56 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48095/tests/dromaeo/cssquery-yui.html) 03:40:57 INFO - PROCESS | 26661 | RSS: Main: 216100864 03:40:57 INFO - PROCESS | 26661 | 03:43:59 INFO - PROCESS | 26661 | Cycle 1(1): loaded http://localhost:48095/tests/dromaeo/cssquery-yui.html (next: http://localhost:48095/tests/dromaeo/cssquery-dojo.html) 03:44:00 INFO - PROCESS | 26661 | RSS: Main: 282890240 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | __start_tp_report 03:44:00 INFO - PROCESS | 26661 | _x_x_mozilla_page_load 03:44:00 INFO - PROCESS | 26661 | _x_x_mozilla_page_load_details 03:44:00 INFO - PROCESS | 26661 | |i|pagename|runs| 03:44:00 INFO - PROCESS | 26661 | |0;dojo.html;2945.4698154332214;3013.4274487847306;3026.60654114965;3051.603291572096;3091.845407729613;5260.158374660055;5357.169638705997;5370.650907691002;5440.972795136024;5456.809011684587;5396.865078373043;5415.512603865652;5426.348838139429;5436.836894893149;5475.370332411774;4866.926996095052;4918.803247870099;4954.777035033432;4966.478519755427;5074.492550744914;4981.72600506972;4990.600751939836;5005.699658020512;5023.497650234984;5040.143175660129;4766.213574760161;4853;4870.951290487085;4880.292357608162;4892.926606100911;4869.050535145648;4891;4962.0324036812835;4965.875853103666;5016.974915125437;4762.952370476286;4916.680415772963;4922.163232250526;4929.630277729185;4955.628327875424;5222.32109825724;5317.335333083364;5326.6537675050995;5367.865803354947;5368.8389348319615;5736.483716465538;5739.311282646067;5761.683107429089;5768.625039372428;5775.451332123442;5544.750486228129;5644.209810626516;5690.089585666274;5824.388439213864;5856.355800861902;5373.946260537345;5395.541378982791;5416.729163541847;5417.972910135463;5420.24116623673;5896.528277737771;5923.49650279731;5926.851828704317;5927.110933359944;5927.73325200367;5496.258005169251;5566.777328906879;5600.047991841397;5611.158326251095;5728.341240757283;5740.225069615632;5786.276715410574;5788.913166302509;5790.536757059425;5796.739146738407;5718.971405143031;5720.713964301768;5723.599348045597;5729.28383952006;5767.769289228468;5459.945400546023;5700.37295897451;5741.024025915604;5765.567582431335;5823.155642431823;4421.602055814928;4426.336049592523;4432.601065904019;4435.268180750205;4472.798724057425;3892.8637497687446;3925.8822235332987;3935.134270460494;3956.287868183697;3973.304671682444;5434.8913021739345;5478.45215478449;5509.311336082989;5530.336359636869;5534.557235421156;5796;5832.620879642895;5839.328477225174;5846.824595262149;5863.149843272701;5224.50367215114;5404.297441332602;5523.143912693539;5532.861678458071;5533.529649979716;4865.756712164449;4908.9263661045115;4909.361782968192;4911.778969946361;4960.627952903546;6020.337762846083;6023.6385816851125;6025.3372129065765;6028.186194863637;6030.246219222598;5533.280673512579;5701.515371193495;5760.596758226884;5763.250777399079;5846.329875880011;6004.669743163993;6015.849603759941;6017.217761690952;6017.488513476404;6073.726682299219;5699.715014249354;5700.002499562477;5703.714814259188;5705.115707065412;5709.229254050816;5557.527610153183;5736.799212027559;5791.594588378773;5795;5802.448767367094;5677.1768093627015;5687.0616348302065;5689.260396148474;5689.26039614864;5731.656100633975;5633.0987042073075;5674.801381951612;5687.914681279702;5694.345150307768;5696.544276457957;5419.674819510842;5518.889622207453;5523.696196709059;5596.832095037155;5598.972005140111;5704.344000440003;5719.142128680731;5734.569907256889;5744.569157313302;5786.392428795084;5569.359523654832;5579.8884022320135;5580.469855363734;5584.106542953271;5592.832215033555 03:44:00 INFO - PROCESS | 26661 | |1;ext.html;592.2448877680959;613.6440864298705;622;627.792828366639;631.9620822750637;43976;45359.54640453603;45535.544644553585;45655.77172114139;48822.755886220606;46541.534584654146;47175.76412117943;47185.29222061663;47274.05451890969;47274.52725472744;9014.639414423431;9114.544272786366;9128.680496182635;9158.542072896362;9160;8940.19538241558;9032.232260257853;9046.411983221102;9049.140331668514;9049.728508144766;9060.818783624323;9093.454392736425;9096.40873343234;9108.407953483036;9122.452652840817;9046.276297896185;9074.546272686339;9078.591463384164;9081.318901082444;9082.318826088036;8993;9073.909260907372;9077.954610226972;9079.909200908038;9086.681966131153;43420.3486947696;43461.34807977883;43514.56485435169;43529.564704353186;43981;3472.131967008248;3686.55761308642;3775.528058992626;3980.800959951991;4225.492940847087;33440.16399590005;34143.48784768231;34458.31083378319;34542.654573454194;34546.13634659129;3617.276544691046;3722.274156539476;3851.114243723959;3873.2447172801317;3930.6658933990643;402564;403642;403701;404012;404072;60328;60587.09119363213;60661.0900836479;60710.69644651748;60721.39278607246;63618.68190659017;63634;63674;63727.68136159382;63790.36209637844;17106.401275955282;17359.56601084983;17385.826141738675;17389.130543473028;17398.21708023142;13635.318234088256;13698.315084245747;13709.245991470565;13711.314434278247;13726.519571815139;14131.364088616037;16712.582185445463;16880.662386752443;16882.324707011583;16902.74645880313;5010.997800439926;5821.854453638693;5858.326292476335;5937.15948056104;5961.76433837311;1004.3672486333709;1038.7718344151904;1067.391586795519;1110.3282514079028;1198.574506050357;32416.928024137596;33563.32873342568;33565;33761.324773504886;33795.66204337925;1097.6981330134117;1108.3128460354633;1120.1542835159403;1129.5086637312795;1133.665568657248;16858.409955651492;17209.7418538722;17441.825581744273;17535.222124417545;17850.285988560314;607661;609644.6624368125;610949;611059;611565;59866.40133598696;59910.70044649749;59919.10121348183;59994.70002650046;60024.69987650034;1229.827824104626;1339.4575197045212;1344.9529266475822;1388.9166650001032;1392.6727219103705;34032;34938.82530587331;34959.65040349666;35047;35110.94667159989;52003;52454.73772631113;53020;53036.73481632722;53080;467372;469106;469265;470564;471643;34842.47736284009;40890.79554602208;41917;42020.369694454;45225.54774452213;50111.74944125256;50789.23816142687;51192.74403627958;51346.45960621187;52096.21855672092;40916.59083409128;42074.7896260529;43756.34365484457;45221.54778452305;46827.06345873132;42781;44020.55979440165;44694.10611787681;44912.32631510595;44913.32630010487;43778.12443751171;44190.558094418644;44730.55269447264;44946.77526612346;45115.323270150315;38090;38742;39142.412863806494;39994;40234.597654023084;29245.41509169762;29258.561121583618;29289.414211716074;29677.99302634592;29952 03:44:00 INFO - PROCESS | 26661 | |2;jquery.html;384.22770231834016;387.517540661876;394.4556512013423;394.6271513345794;400.6114069352727;14447;18837.905810470944;19152;20425.28511502098;20429.387118386454;14991.175485348302;18180.727289090675;18941;19874;20171;5884;6021.066734656135;6027.789027384042;6036.0644100164545;6055.061465472859;4297.269464191095;5958.433948774859;5998.700064996755;6369.490440764727;6770.5599136056;6713.227978782429;6816.522843400964;6822.692978815965;6864.691088900987;6872.484563657721;6467.805965821033;6609.239937407212;6758.425533829606;6779.457643388541;6801.931980680204;5697.1739097831;5753.453421924911;5767.1349297605275;5794.188813566104;5797.130430435426;24563.263102106965;24581.508369832503;25393.49213015748;25728.614070788954;25835.22494325173;2408.0849277274524;2458.30815257293;2505.6742623459018;2638.56463683492;2650.628911952328;15602.375904963788;16260.837391626168;16464.42374516886;16999.83000169995;17133;2011.3463124484601;2046.549759053006;2095.298075144828;2096.976417756872;2112.207922029239;82188;98252.50873745585;99909.99999999927;101622;102393.48803255935;17578.648427031643;18226;18453.7231941521;18582;18816.81183188178;32454.513182301813;32890.50664240038;33080;33081.17297067593;33197.33605327928;10289.639862604772;10431.113355364727;10454.372737635766;10600.469976501145;10646.254762166574;6848.178218613699;8734.170253825876;8799.384043117048;8847.73456796297;8998.280137588978;8250.587470626564;9026.097390261053;9193.586288617029;9377.859332110023;9389.436633801994;3559.618362555531;3580.4629305604367;3651.3457433309713;3666.7616604920595;3681.5214022177483;945.3052006775152;1001.7695929936156;1009.5759780891896;1039.7712503249272;1075.833245846895;19885.60228795416;20517.58964820695;20970.580588388148;20971.8951405242;21320.893395533232;984.7045886234101;1010.3988127064385;1013.6857574152036;1020.8417695257247;1038.1642777563982;11049.28179668319;11560.364179970014;11579.594714184963;11584.189106762446;11596.942015290038;540052;544997;561531;569410;570794;97942;98407;99250;100996;101369;989.223459584223;1000.5947591225565;1007.5113569918584;1031.8606988056517;1036.4558606731375;17546.561335966704;17985.75178102737;18363.449096527125;18471;18663.626727465646;42639.36040959326;43648.781756092285;43922.121557569306;44692.55307447014;45209.77395113003;61470;62534.374656252854;63844.68077659582;64031.35968640254;65473.34526654863;8891.288696904361;9045.592948317473;9088.272938164799;9134.22359099484;9478.052194780466;10701.464926753475;12260.693482663004;14240.857591423954;14457.493987710379;14459.13245205291;12189.939050305047;12206.938965305117;12454;12534.623961281177;12744.808827867404;12233.816492752794;12238.08214383907;12349.567765128177;12568.560100396808;12653.304068276333;11927.701807454883;12524.749505010031;12549.372531373578;12930.612081637566;12944.676383090122;8683.175098365646;8976.82046359056;9367.718968430958;9480.383775054599;9512.952435238056;12226.69075014711;12728.74542509163;12863.935680321913;12944.676383090498;12977.610671679864 03:44:00 INFO - PROCESS | 26661 | |3;mootools.html;1326.43626458755;1491;1502;1676.2791999440244;1766.7526546283523;8278.254957053863;8649.870251946219;8661.61022753976;8878.600462979166;8886;8692.217700406962;8847.380683352168;8850.468971861692;8852.070532594065;8855.203031727144;2044.9386518404472;2095.947601309972;2162.545865368271;2187.628103222448;2191.1454532732246;2284.2804516577226;2287.4738810073695;2289.175896677195;2293.587154312223;2294.541091781642;2262.2421488801187;2276.6243569810963;2282.7945484906354;2283.748787633359;2284.0292875527916;1975.1309423853531;2054.5479994401207;2116.4814620418097;2121.1303365620174;2201.52667176557;2197.505561248722;2210.049678638185;2215.1582398688556;2217.7227846519186;2219.711437513129;8926.776830579287;8950.328725345626;8950.462972221687;8950.910490895138;8954.955225223897;5186.455422180692;5296.9735151324385;5304.708241046741;5312.893742125175;5323.121684921984;3243.9026829195163;3344.2642618623863;3458.15275257561;3466.480027995821;3483.4948932404914;1099.2140619457127;1105.778844231149;1113.2986218682179;1114.899659030691;1128.0524359538028;13133.540326088636;13152.539661111816;13167.802482962763;13184.406701698446;13194.07641465098;4470.2177119004045;4570.54294570547;4730.053989202104;4756.143894099096;4766.165920963817;10926.399048052433;10928.672139835817;10942.069924056545;10949.616763413242;10959.397233152094;5294.841154765363;5297.311349524614;5308.495692909245;5308.522232999048;5311.7344132793205;4298.656107511391;4724.503927087675;4859.198232291703;4876.5854902332985;4937.901241975141;4078.6940979426663;4083.6324730773777;4138.213739389506;4148.315527937856;4169.937450938239;1161.4425075963584;1211.0432758121162;1253.6301790971686;1275.9744805103846;1289.5873320537337;817.9897826184776;855.2516548020482;940.8541676040225;971.7522031881825;1018.8828284747201;6398.072279519537;6421.29365769765;6565.409113179837;6578;6596.73613055482;1002.8245057114975;1004.7136566078779;1007.5314978535016;1038.2161468091542;1068.6793961811425;4129.463169787909;4173.8956526087095;4185.686073544496;4200;4212.347086201644;10835.133189344833;11133.443327833738;11222.102231821435;11225.270357426742;11268.267562608404;11136.832947505794;11139.443027848574;11146.498407571678;11155.330680159217;11175.273607215506;7923.12845586985;8175.427720059658;8563.143685631387;8693.869591956125;8697.956510217535;6301.117843502005;6538.313477084837;6565.29205345814;6643.40209381158;6704.396604305636;10720.624778132727;10749.838752418877;10979.780404392028;11005.944970275097;11014.39420831862;10628.627998020342;10841.512131953923;11033.889661103287;11176.720581985515;11200.047995920439;7365.9631701841145;7845.13703492604;7996.680132794856;8099.230573095547;8170;8748.518831464331;8761.080086590817;8786.604602792762;8826.735197944072;8827.382083254111;7613.086429628259;7856.135825059353;7875.606219689107;7890.408219383455;7958.721444749406;7647.732329368446;7722;7832.412569057229;7870.921290787019;7886.605669716377;7190.20907700163;7256.96371518139;7308.086489188851;7310.122785265802;7323.670434830339;5345.839624811262;5364.865878353073;5417.891642167214;5497.8075767347955;5589.441055894541;5399.973000134974;5407.83776486706;5494;5507.642003269774;5518.586106041983 03:44:00 INFO - PROCESS | 26661 | |4;prototype.html;314.4528520374549;326.11134658056784;328.0372107863421;333.1803762743651;336.50197707393056;5263.76313065912;5271.051210782058;5272.710000949946;5279.260903473512;5284.2073688946675;5160.741962901859;5222.791088356471;5223.843284701456;5225.973870130644;5230.091016778877;3878.1661942682317;4128.782009307261;4131.442255295527;4134.6692264618905;4136.276151673445;4070.409790580364;4110.671146308288;4135.338345864662;4146.438917191081;4150.128473020654;4076.449679293302;4081.163361510898;4082.5713300103507;4098.4057311689785;4103.5280942691525;3899.415087736834;3928.292907276689;4029.717919745619;4097.815598298069;4109.198706252282;3542.6988705960025;3558.5729712434413;3565.964340356589;3573.928521429557;3576.2847430514003;5108.216022267873;5110.2590124431745;5110.795568177268;5117.744112794346;5118.6416950813455;4455.242608756486;4550.0889626356675;4658.277966915133;4695.882602934954;4700.858974230779;5406.648108073001;5462;5475.890482190334;5476.836649523416;5487.64330318528;3941.2708648899757;3957.7229593928437;3964.7224694271413;3980.542237642679;3988.641022308006;5414.8917021659345;5622.184783206412;5708;5712.971435142839;5734.971325143348;3949.1311911379453;3963.8216280267457;3965.5439624442984;3965.92068158641;3968.543617483969;4005.939910901339;4008.819603117867;4010.2981978153707;4014.759114453142;4016.136530645925;4446.6220371268155;4601.907961840744;4602.723836569817;4619.722816631013;4622.260438329851;5502.724863756796;5512.924979628934;5512.932650256154;5524.889502210013;5526;1396.9173890234947;1412.3362019850858;1416.0370947755418;1416.7803990381508;1418.4042702065158;856.2935578148052;905.8550631898864;906.7959709065408;923.6951805904035;924.5977999570208;4707.8587642370785;4730.929036064462;4740.715557066587;4750.767139444456;4757.35775670287;4983.25251212321;4992.400911890524;5026.436517376164;5028.270900719448;5044.578602270857;4711.199096153594;4743.383360163157;4747.976260118678;4752.952470475251;5082.974585127051;2988.1782509809977;3002.3695024045196;3016.9547456788164;3018.4868572342752;3025.9106721580215;5770.567207459458;5785.363610002896;5824.271966004249;5841.474267315962;5844.47399734026;5547.750351234205;5751.1373294006235;5753.180941338797;5769.826905192851;5779.959607270647;5794.275715535558;5823.650580965071;5825.349465992963;5826.592138550345;5831.94168058314;5443.482869127427;5712.0860662293835;5713.685747283939;5723.313202415737;5723.484886360247;5605.383407825055;5607.8878422432135;5642.153676948491;5675.914861277001;5675.971620141873;5813.76744930198;5815.709214539341;5819.214406055297;5832.533397328119;5847.239858818326;4003.5195776507007;4084.7549147051272;4094.9795251023556;4188.204241194163;4219.7046206765235;5604.057869618483;5635.436456354331;5638.126090455986;5646.604737668324;5650.63270887391;5479.726013699219;5483.369412517612;5489.819688766935;5498.642588231752;5508.980838544882;5344.877575709144;5420.186971954238;5485.259489968803;5486.890262194654;5487.286652735119;5395.946040539544;5406.810761623325;5410.3778065522965;5415.1335786274;5421.484958928895;5309.389420216725;5361.919571206513;5554.1946417770005;5557.805476808292;5566.749496272759;5424.186372044225;5464.316960379952;5470.058129364178;5474.479924407175;5480.451954804487 03:44:00 INFO - PROCESS | 26661 | |5;yui.html;759.2088765988236;775.2557544757033;785.5757890739001;829.8298848736008;842.3135144856944;15474;16870.409535666255;16870.83129168708;17208.82791172088;18059.729104063448;17023.148842557883;17054.403095891645;17173.398931037416;17190.39833605824;17236.13819309036;674.0462245922015;813.0893399392688;814.9877751833727;831.1895901496023;831.9376046796484;820.6553247636007;842.3682238321259;842.5787106446777;842.6503001254472;842.873568964654;872.8516152254132;880.106691707917;880.6389380354057;881.2245224202693;881.6209030117047;691.7855464805926;833.9916600833975;860.6729442811692;863.8272345530887;871.7428358634218;631.9304876463585;632.8607706304606;633.0441034038589;633.3255083336232;633.4045996763028;14875.033122847088;14928.178950157737;14931.850681493155;14956;14970.550883473514;14685.265736713229;14925.776113358308;15038.248087595577;15069.47256846005;15124;13780.931095344458;13796.241206733626;13883.514077007256;14020.369083391271;14297;4540.704854184468;4581.496035436099;4633.930491042637;4759.238521836524;4772.47502774698;82509;83098.58450707768;83300;84349.15650843474;84457;11499.540018399337;11633.59282425128;11665.76668466626;11697.181197316106;11736.823947640793;21113.683294750284;21118.47203819917;21123.78876211218;21126;21129.577408451743;8559.752321981425;8733.694320698745;8825.470471771585;8940.374173807772;8964.910350896538;4822.132016237041;4905.632077594195;4908.803647854046;4934.309196712463;4947.480514545993;3478.2695633916655;3624.075860655516;3624.583172935146;3632.328019316435;3641.7997010164695;651.563452486835;654.6334052930374;657.7697805767943;663.6582160187507;682.9897551536731;578.4389142531737;668.355037388922;706.1067749297143;725.4776560876161;740.526063319476;9679.225661947028;9699.224062075016;9898.851517227247;9924.956186995321;10015.649452269281;619.8140557832633;630.8675178212627;633.9461145802659;725.5356571794056;728.6611725547646;4732.668713190113;4855.295982082649;4919.581835543947;4920.655554111249;4927.77824997876;121039;122863;125425;126160.36919815342;130101;59408;85986;90323;102127;102305.48847255716;91601;93542.53228733812;94290;94881;95461;9508.144267016001;9621.27840411958;9682.273829462678;9686.854697179408;9780.804383912424;56010.17913277683;56164.43835561755;57465.71267143638;57938.4206157933;58726.412735872094;72991.63504182445;75850.62074689592;81583;81892.59053704693;82000.58999704964;11393.544258229578;11511.712207194887;11602.245854019462;11668.766624667289;12186.025117990717;12171.33057681836;12322.199057061262;12492.188007779465;13626.863731362559;14062.859371406565;12171.573994910135;12388.62834114978;12393.690157746129;12524.532202729804;12572.622821315375;12383.133180677267;12488.812667810173;12625.242485450903;12748.68128296763;13034.4134513949;12240.387980600755;12528.123031387715;12584.93707531493;12644;12688.809667855174;9906.127460688353;10299.691009269734;10454.529546170288;10456.424896630759;10787.460626968777;9884.802303953737;9963.25275604347;10079.546420410952;10138.391696498235;10164.288499805238 03:44:00 INFO - PROCESS | 26661 | __end_tp_report 03:44:00 INFO - PROCESS | 26661 | __start_cc_report 03:44:00 INFO - PROCESS | 26661 | _x_x_mozilla_cycle_collect,939 03:44:00 INFO - PROCESS | 26661 | __end_cc_report 03:44:00 INFO - PROCESS | 26661 | __startTimestamp1468061040183__endTimestamp 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | ------- Summary: start ------- 03:44:00 INFO - PROCESS | 26661 | Number of tests: 6 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#0] dojo.html Cycles:165 Average:5360.97 Median:5557.53 stddev:620.28 (11.2%) stddev-sans-first:592.53 03:44:00 INFO - PROCESS | 26661 | Values: 2945.5 3013.4 3026.6 3051.6 3091.8 5260.2 5357.2 5370.7 5441.0 5456.8 5396.9 5415.5 5426.3 5436.8 5475.4 4866.9 4918.8 4954.8 4966.5 5074.5 4981.7 4990.6 5005.7 5023.5 5040.1 4766.2 4853.0 4871.0 4880.3 4892.9 4869.1 4891.0 4962.0 4965.9 5017.0 4763.0 4916.7 4922.2 4929.6 4955.6 5222.3 5317.3 5326.7 5367.9 5368.8 5736.5 5739.3 5761.7 5768.6 5775.5 5544.8 5644.2 5690.1 5824.4 5856.4 5373.9 5395.5 5416.7 5418.0 5420.2 5896.5 5923.5 5926.9 5927.1 5927.7 5496.3 5566.8 5600.0 5611.2 5728.3 5740.2 5786.3 5788.9 5790.5 5796.7 5719.0 5720.7 5723.6 5729.3 5767.8 5459.9 5700.4 5741.0 5765.6 5823.2 4421.6 4426.3 4432.6 4435.3 4472.8 3892.9 3925.9 3935.1 3956.3 3973.3 5434.9 5478.5 5509.3 5530.3 5534.6 5796.0 5832.6 5839.3 5846.8 5863.1 5224.5 5404.3 5523.1 5532.9 5533.5 4865.8 4908.9 4909.4 4911.8 4960.6 6020.3 6023.6 6025.3 6028.2 6030.2 5533.3 5701.5 5760.6 5763.3 5846.3 6004.7 6015.8 6017.2 6017.5 6073.7 5699.7 5700.0 5703.7 5705.1 5709.2 5557.5 5736.8 5791.6 5795.0 5802.4 5677.2 5687.1 5689.3 5689.3 5731.7 5633.1 5674.8 5687.9 5694.3 5696.5 5419.7 5518.9 5523.7 5596.8 5599.0 5704.3 5719.1 5734.6 5744.6 5786.4 5569.4 5579.9 5580.5 5584.1 5592.8 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#1] ext.html Cycles:180 Average:66119.12 Median:44105.56 stddev:133316.27 (302.3%) stddev-sans-first:133599.48 03:44:00 INFO - PROCESS | 26661 | Values: 592.2 613.6 622.0 627.8 632.0 43976.0 45359.5 45535.5 45655.8 48822.8 46541.5 47175.8 47185.3 47274.1 47274.5 9014.6 9114.5 9128.7 9158.5 9160.0 8940.2 9032.2 9046.4 9049.1 9049.7 9060.8 9093.5 9096.4 9108.4 9122.5 9046.3 9074.5 9078.6 9081.3 9082.3 8993.0 9073.9 9078.0 9079.9 9086.7 43420.3 43461.3 43514.6 43529.6 43981.0 3472.1 3686.6 3775.5 3980.8 4225.5 33440.2 34143.5 34458.3 34542.7 34546.1 3617.3 3722.3 3851.1 3873.2 3930.7 402564.0 403642.0 403701.0 404012.0 404072.0 60328.0 60587.1 60661.1 60710.7 60721.4 63618.7 63634.0 63674.0 63727.7 63790.4 17106.4 17359.6 17385.8 17389.1 17398.2 13635.3 13698.3 13709.2 13711.3 13726.5 14131.4 16712.6 16880.7 16882.3 16902.7 5011.0 5821.9 5858.3 5937.2 5961.8 1004.4 1038.8 1067.4 1110.3 1198.6 32416.9 33563.3 33565.0 33761.3 33795.7 1097.7 1108.3 1120.2 1129.5 1133.7 16858.4 17209.7 17441.8 17535.2 17850.3 607661.0 609644.7 610949.0 611059.0 611565.0 59866.4 59910.7 59919.1 59994.7 60024.7 1229.8 1339.5 1345.0 1388.9 1392.7 34032.0 34938.8 34959.7 35047.0 35110.9 52003.0 52454.7 53020.0 53036.7 53080.0 467372.0 469106.0 469265.0 470564.0 471643.0 34842.5 40890.8 41917.0 42020.4 45225.5 50111.7 50789.2 51192.7 51346.5 52096.2 40916.6 42074.8 43756.3 45221.5 46827.1 42781.0 44020.6 44694.1 44912.3 44913.3 43778.1 44190.6 44730.6 44946.8 45115.3 38090.0 38742.0 39142.4 39994.0 40234.6 29245.4 29258.6 29289.4 29678.0 29952.0 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#2] jquery.html Cycles:180 Average:33175.24 Median:13949.58 stddev:91830.46 (658.3%) stddev-sans-first:92055.06 03:44:00 INFO - PROCESS | 26661 | Values: 384.2 387.5 394.5 394.6 400.6 14447.0 18837.9 19152.0 20425.3 20429.4 14991.2 18180.7 18941.0 19874.0 20171.0 5884.0 6021.1 6027.8 6036.1 6055.1 4297.3 5958.4 5998.7 6369.5 6770.6 6713.2 6816.5 6822.7 6864.7 6872.5 6467.8 6609.2 6758.4 6779.5 6801.9 5697.2 5753.5 5767.1 5794.2 5797.1 24563.3 24581.5 25393.5 25728.6 25835.2 2408.1 2458.3 2505.7 2638.6 2650.6 15602.4 16260.8 16464.4 16999.8 17133.0 2011.3 2046.5 2095.3 2097.0 2112.2 82188.0 98252.5 99910.0 101622.0 102393.5 17578.6 18226.0 18453.7 18582.0 18816.8 32454.5 32890.5 33080.0 33081.2 33197.3 10289.6 10431.1 10454.4 10600.5 10646.3 6848.2 8734.2 8799.4 8847.7 8998.3 8250.6 9026.1 9193.6 9377.9 9389.4 3559.6 3580.5 3651.3 3666.8 3681.5 945.3 1001.8 1009.6 1039.8 1075.8 19885.6 20517.6 20970.6 20971.9 21320.9 984.7 1010.4 1013.7 1020.8 1038.2 11049.3 11560.4 11579.6 11584.2 11596.9 540052.0 544997.0 561531.0 569410.0 570794.0 97942.0 98407.0 99250.0 100996.0 101369.0 989.2 1000.6 1007.5 1031.9 1036.5 17546.6 17985.8 18363.4 18471.0 18663.6 42639.4 43648.8 43922.1 44692.6 45209.8 61470.0 62534.4 63844.7 64031.4 65473.3 8891.3 9045.6 9088.3 9134.2 9478.1 10701.5 12260.7 14240.9 14457.5 14459.1 12189.9 12206.9 12454.0 12534.6 12744.8 12233.8 12238.1 12349.6 12568.6 12653.3 11927.7 12524.7 12549.4 12930.6 12944.7 8683.2 8976.8 9367.7 9480.4 9513.0 12226.7 12728.7 12863.9 12944.7 12977.6 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#3] mootools.html Cycles:180 Average:5978.53 Median:4255.50 stddev:3486.86 (81.9%) stddev-sans-first:3479.12 03:44:00 INFO - PROCESS | 26661 | Values: 1326.4 1491.0 1502.0 1676.3 1766.8 8278.3 8649.9 8661.6 8878.6 8886.0 8692.2 8847.4 8850.5 8852.1 8855.2 2044.9 2095.9 2162.5 2187.6 2191.1 2284.3 2287.5 2289.2 2293.6 2294.5 2262.2 2276.6 2282.8 2283.7 2284.0 1975.1 2054.5 2116.5 2121.1 2201.5 2197.5 2210.0 2215.2 2217.7 2219.7 8926.8 8950.3 8950.5 8950.9 8955.0 5186.5 5297.0 5304.7 5312.9 5323.1 3243.9 3344.3 3458.2 3466.5 3483.5 1099.2 1105.8 1113.3 1114.9 1128.1 13133.5 13152.5 13167.8 13184.4 13194.1 4470.2 4570.5 4730.1 4756.1 4766.2 10926.4 10928.7 10942.1 10949.6 10959.4 5294.8 5297.3 5308.5 5308.5 5311.7 4298.7 4724.5 4859.2 4876.6 4937.9 4078.7 4083.6 4138.2 4148.3 4169.9 1161.4 1211.0 1253.6 1276.0 1289.6 818.0 855.3 940.9 971.8 1018.9 6398.1 6421.3 6565.4 6578.0 6596.7 1002.8 1004.7 1007.5 1038.2 1068.7 4129.5 4173.9 4185.7 4200.0 4212.3 10835.1 11133.4 11222.1 11225.3 11268.3 11136.8 11139.4 11146.5 11155.3 11175.3 7923.1 8175.4 8563.1 8693.9 8698.0 6301.1 6538.3 6565.3 6643.4 6704.4 10720.6 10749.8 10979.8 11005.9 11014.4 10628.6 10841.5 11033.9 11176.7 11200.0 7366.0 7845.1 7996.7 8099.2 8170.0 8748.5 8761.1 8786.6 8826.7 8827.4 7613.1 7856.1 7875.6 7890.4 7958.7 7647.7 7722.0 7832.4 7870.9 7886.6 7190.2 7257.0 7308.1 7310.1 7323.7 5345.8 5364.9 5417.9 5497.8 5589.4 5400.0 5407.8 5494.0 5507.6 5518.6 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#4] prototype.html Cycles:180 Average:4581.88 Median:5191.77 stddev:1346.76 (25.9%) stddev-sans-first:1311.89 03:44:00 INFO - PROCESS | 26661 | Values: 314.5 326.1 328.0 333.2 336.5 5263.8 5271.1 5272.7 5279.3 5284.2 5160.7 5222.8 5223.8 5226.0 5230.1 3878.2 4128.8 4131.4 4134.7 4136.3 4070.4 4110.7 4135.3 4146.4 4150.1 4076.4 4081.2 4082.6 4098.4 4103.5 3899.4 3928.3 4029.7 4097.8 4109.2 3542.7 3558.6 3566.0 3573.9 3576.3 5108.2 5110.3 5110.8 5117.7 5118.6 4455.2 4550.1 4658.3 4695.9 4700.9 5406.6 5462.0 5475.9 5476.8 5487.6 3941.3 3957.7 3964.7 3980.5 3988.6 5414.9 5622.2 5708.0 5713.0 5735.0 3949.1 3963.8 3965.5 3965.9 3968.5 4005.9 4008.8 4010.3 4014.8 4016.1 4446.6 4601.9 4602.7 4619.7 4622.3 5502.7 5512.9 5512.9 5524.9 5526.0 1396.9 1412.3 1416.0 1416.8 1418.4 856.3 905.9 906.8 923.7 924.6 4707.9 4730.9 4740.7 4750.8 4757.4 4983.3 4992.4 5026.4 5028.3 5044.6 4711.2 4743.4 4748.0 4753.0 5083.0 2988.2 3002.4 3017.0 3018.5 3025.9 5770.6 5785.4 5824.3 5841.5 5844.5 5547.8 5751.1 5753.2 5769.8 5780.0 5794.3 5823.7 5825.3 5826.6 5831.9 5443.5 5712.1 5713.7 5723.3 5723.5 5605.4 5607.9 5642.2 5675.9 5676.0 5813.8 5815.7 5819.2 5832.5 5847.2 4003.5 4084.8 4095.0 4188.2 4219.7 5604.1 5635.4 5638.1 5646.6 5650.6 5479.7 5483.4 5489.8 5498.6 5509.0 5344.9 5420.2 5485.3 5486.9 5487.3 5395.9 5406.8 5410.4 5415.1 5421.5 5309.4 5361.9 5554.2 5557.8 5566.7 5424.2 5464.3 5470.1 5474.5 5480.5 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - PROCESS | 26661 | [#5] yui.html Cycles:180 Average:21477.30 Median:4920.12 stddev:31498.68 (640.2%) stddev-sans-first:31548.63 03:44:00 INFO - PROCESS | 26661 | Values: 759.2 775.3 785.6 829.8 842.3 15474.0 16870.4 16870.8 17208.8 18059.7 17023.1 17054.4 17173.4 17190.4 17236.1 674.0 813.1 815.0 831.2 831.9 820.7 842.4 842.6 842.7 842.9 872.9 880.1 880.6 881.2 881.6 691.8 834.0 860.7 863.8 871.7 631.9 632.9 633.0 633.3 633.4 14875.0 14928.2 14931.9 14956.0 14970.6 14685.3 14925.8 15038.2 15069.5 15124.0 13780.9 13796.2 13883.5 14020.4 14297.0 4540.7 4581.5 4633.9 4759.2 4772.5 82509.0 83098.6 83300.0 84349.2 84457.0 11499.5 11633.6 11665.8 11697.2 11736.8 21113.7 21118.5 21123.8 21126.0 21129.6 8559.8 8733.7 8825.5 8940.4 8964.9 4822.1 4905.6 4908.8 4934.3 4947.5 3478.3 3624.1 3624.6 3632.3 3641.8 651.6 654.6 657.8 663.7 683.0 578.4 668.4 706.1 725.5 740.5 9679.2 9699.2 9898.9 9925.0 10015.6 619.8 630.9 633.9 725.5 728.7 4732.7 4855.3 4919.6 4920.7 4927.8 121039.0 122863.0 125425.0 126160.4 130101.0 59408.0 85986.0 90323.0 102127.0 102305.5 91601.0 93542.5 94290.0 94881.0 95461.0 9508.1 9621.3 9682.3 9686.9 9780.8 56010.2 56164.4 57465.7 57938.4 58726.4 72991.6 75850.6 81583.0 81892.6 82000.6 11393.5 11511.7 11602.2 11668.8 12186.0 12171.3 12322.2 12492.2 13626.9 14062.9 12171.6 12388.6 12393.7 12524.5 12572.6 12383.1 12488.8 12625.2 12748.7 13034.4 12240.4 12528.1 12584.9 12644.0 12688.8 9906.1 10299.7 10454.5 10456.4 10787.5 9884.8 9963.3 10079.5 10138.4 10164.3 03:44:00 INFO - PROCESS | 26661 | -------- Summary: end -------- 03:44:00 INFO - PROCESS | 26661 | 03:44:00 INFO - TEST-INFO | 26661: exit 0 03:44:01 INFO - TEST-OK | dromaeo_css | took 1112283ms 03:44:01 INFO - TEST-START | kraken 03:44:01 INFO - operating with platform_type : linux_ 03:44:01 INFO - Initialising browser for kraken test... 03:44:01 INFO - TEST-INFO | started process 26854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprigF_6/profile http://localhost:48095/getInfo.html) 03:44:04 INFO - PROCESS | 26854 | __metrics Screen width/height:1600/1200 03:44:04 INFO - PROCESS | 26854 | colorDepth:24 03:44:04 INFO - PROCESS | 26854 | Browser inner width/height: 1024/697 03:44:04 INFO - PROCESS | 26854 | __metrics 03:44:05 INFO - PROCESS | 26854 | 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:44:05 INFO - PROCESS | 26854 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:05 INFO - TEST-INFO | 26854: exit 0 03:44:05 INFO - Browser initialized. 03:44:05 INFO - Running cycle 1/1 for kraken test... 03:44:05 INFO - TEST-INFO | started process 26937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprigF_6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:17 INFO - PROCESS | 26937 | RSS: Main: 157810688 03:44:17 INFO - PROCESS | 26937 | 03:44:20 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48095/tests/kraken/driver.html?testName=audio-beat-detection) 03:44:21 INFO - PROCESS | 26937 | RSS: Main: 215916544 03:44:21 INFO - PROCESS | 26937 | 03:44:23 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48095/tests/kraken/driver.html?testName=audio-dft) 03:44:24 INFO - PROCESS | 26937 | RSS: Main: 203071488 03:44:24 INFO - PROCESS | 26937 | 03:44:27 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48095/tests/kraken/driver.html?testName=audio-fft) 03:44:28 INFO - PROCESS | 26937 | RSS: Main: 268824576 03:44:28 INFO - PROCESS | 26937 | 03:44:30 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48095/tests/kraken/driver.html?testName=audio-oscillator) 03:44:30 INFO - PROCESS | 26937 | RSS: Main: 180224000 03:44:30 INFO - PROCESS | 26937 | 03:44:32 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48095/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:44:33 INFO - PROCESS | 26937 | RSS: Main: 184152064 03:44:33 INFO - PROCESS | 26937 | 03:44:36 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48095/tests/kraken/driver.html?testName=imaging-darkroom) 03:44:37 INFO - PROCESS | 26937 | RSS: Main: 218492928 03:44:37 INFO - PROCESS | 26937 | 03:44:40 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48095/tests/kraken/driver.html?testName=imaging-desaturate) 03:44:41 INFO - PROCESS | 26937 | RSS: Main: 221626368 03:44:41 INFO - PROCESS | 26937 | 03:44:43 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48095/tests/kraken/driver.html?testName=json-parse-financial) 03:44:44 INFO - PROCESS | 26937 | RSS: Main: 221442048 03:44:44 INFO - PROCESS | 26937 | 03:44:46 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48095/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:46 INFO - PROCESS | 26937 | RSS: Main: 207794176 03:44:46 INFO - PROCESS | 26937 | 03:44:48 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:48 INFO - PROCESS | 26937 | RSS: Main: 212197376 03:44:48 INFO - PROCESS | 26937 | 03:44:50 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:44:51 INFO - PROCESS | 26937 | RSS: Main: 209494016 03:44:51 INFO - PROCESS | 26937 | 03:44:54 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:44:54 INFO - PROCESS | 26937 | RSS: Main: 225259520 03:44:54 INFO - PROCESS | 26937 | 03:44:57 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:57 INFO - PROCESS | 26937 | RSS: Main: 224342016 03:44:57 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | Cycle 1(1): loaded http://localhost:48095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48095/tests/kraken/driver.html?testName=ai-astar) 03:44:59 INFO - PROCESS | 26937 | RSS: Main: 267350016 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | __start_tp_report 03:44:59 INFO - PROCESS | 26937 | _x_x_mozilla_page_load 03:44:59 INFO - PROCESS | 26937 | _x_x_mozilla_page_load_details 03:44:59 INFO - PROCESS | 26937 | |i|pagename|runs| 03:44:59 INFO - PROCESS | 26937 | |0;ai-astar;101;112;110;109;111;110;110;110;109;110 03:44:59 INFO - PROCESS | 26937 | |1;audio-beat-detection;146;117;118;176;118;116;147;116;145;118 03:44:59 INFO - PROCESS | 26937 | |2;audio-dft;160;161;160;160;159;161;160;161;164;161 03:44:59 INFO - PROCESS | 26937 | |3;audio-fft;76;75;77;125;75;76;75;74;100;75 03:44:59 INFO - PROCESS | 26937 | |4;audio-oscillator;84;85;118;84;84;84;121;83;84;121 03:44:59 INFO - PROCESS | 26937 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;118;117;116 03:44:59 INFO - PROCESS | 26937 | |6;imaging-darkroom;146;146;145;146;146;146;145;147;146;145 03:44:59 INFO - PROCESS | 26937 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;94 03:44:59 INFO - PROCESS | 26937 | |8;json-parse-financial;53;54;53;53;54;52;54;54;54;53 03:44:59 INFO - PROCESS | 26937 | |9;json-stringify-tinderbox;61;61;60;60;61;61;61;61;61;62 03:44:59 INFO - PROCESS | 26937 | |10;stanford-crypto-aes;74;75;74;74;74;74;75;74;75;79 03:44:59 INFO - PROCESS | 26937 | |11;stanford-crypto-ccm;118;116;116;119;117;120;118;117;167;135 03:44:59 INFO - PROCESS | 26937 | |12;stanford-crypto-pbkdf2;156;162;153;159;155;153;153;157;153;152 03:44:59 INFO - PROCESS | 26937 | |13;stanford-crypto-sha256-iterative;62;62;62;66;63;61;62;63;60;60 03:44:59 INFO - PROCESS | 26937 | __end_tp_report 03:44:59 INFO - PROCESS | 26937 | __start_cc_report 03:44:59 INFO - PROCESS | 26937 | _x_x_mozilla_cycle_collect,1650 03:44:59 INFO - PROCESS | 26937 | __end_cc_report 03:44:59 INFO - PROCESS | 26937 | __startTimestamp1468061099837__endTimestamp 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | ------- Summary: start ------- 03:44:59 INFO - PROCESS | 26937 | Number of tests: 14 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:3.01 (2.7%) stddev-sans-first:0.93 03:44:59 INFO - PROCESS | 26937 | Values: 101.0 112.0 110.0 109.0 111.0 110.0 110.0 110.0 109.0 110.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:131.50 stddev:20.68 (15.7%) stddev-sans-first:21.28 03:44:59 INFO - PROCESS | 26937 | Values: 146.0 117.0 118.0 176.0 118.0 116.0 147.0 116.0 145.0 118.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.34 (0.8%) stddev-sans-first:1.39 03:44:59 INFO - PROCESS | 26937 | Values: 160.0 161.0 160.0 160.0 159.0 161.0 160.0 161.0 164.0 161.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:16.75 (22.3%) stddev-sans-first:17.58 03:44:59 INFO - PROCESS | 26937 | Values: 76.0 75.0 77.0 125.0 75.0 76.0 75.0 74.0 100.0 75.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:84.00 stddev:17.42 (20.7%) stddev-sans-first:18.03 03:44:59 INFO - PROCESS | 26937 | Values: 84.0 85.0 118.0 84.0 84.0 84.0 121.0 83.0 84.0 121.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 03:44:59 INFO - PROCESS | 26937 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 118.0 117.0 116.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 03:44:59 INFO - PROCESS | 26937 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 147.0 146.0 145.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 03:44:59 INFO - PROCESS | 26937 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 03:44:59 INFO - PROCESS | 26937 | Values: 53.0 54.0 53.0 53.0 54.0 52.0 54.0 54.0 54.0 53.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 03:44:59 INFO - PROCESS | 26937 | Values: 61.0 61.0 60.0 60.0 61.0 61.0 61.0 61.0 61.0 62.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.50 stddev:1.55 (2.1%) stddev-sans-first:1.62 03:44:59 INFO - PROCESS | 26937 | Values: 74.0 75.0 74.0 74.0 74.0 74.0 75.0 74.0 75.0 79.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:118.50 stddev:16.01 (13.5%) stddev-sans-first:16.82 03:44:59 INFO - PROCESS | 26937 | Values: 118.0 116.0 116.0 119.0 117.0 120.0 118.0 117.0 167.0 135.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.30 Median:155.50 stddev:3.23 (2.1%) stddev-sans-first:3.42 03:44:59 INFO - PROCESS | 26937 | Values: 156.0 162.0 153.0 159.0 155.0 153.0 153.0 157.0 153.0 152.0 03:44:59 INFO - PROCESS | 26937 | 03:44:59 INFO - PROCESS | 26937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.73 (2.8%) stddev-sans-first:1.83 03:44:59 INFO - PROCESS | 26937 | Values: 62.0 62.0 62.0 66.0 63.0 61.0 62.0 63.0 60.0 60.0 03:44:59 INFO - PROCESS | 26937 | -------- Summary: end -------- 03:44:59 INFO - PROCESS | 26937 | 03:45:00 INFO - TEST-INFO | 26937: exit 0 03:45:00 INFO - TEST-OK | kraken | took 59771ms 03:45:00 INFO - SUITE-END | took 1172s 03:45:01 INFO - Completed test suite (00:19:32) 03:45:01 INFO - javascript benchmark 03:45:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2945.4698154332214, 3013.4274487847306, 3026.60654114965, 3051.603291572096, 3091.845407729613, 5260.158374660055, 5357.169638705997, 5370.650907691002, 5440.972795136024, 5456.809011684587, 5396.865078373043, 5415.512603865652, 5426.348838139429, 5436.836894893149, 5475.370332411774, 4866.926996095052, 4918.803247870099, 4954.777035033432, 4966.478519755427, 5074.492550744914, 4981.72600506972, 4990.600751939836, 5005.699658020512, 5023.497650234984, 5040.143175660129, 4766.213574760161, 4853.0, 4870.951290487085, 4880.292357608162, 4892.926606100911, 4869.050535145648, 4891.0, 4962.0324036812835, 4965.875853103666, 5016.974915125437, 4762.952370476286, 4916.680415772963, 4922.163232250526, 4929.630277729185, 4955.628327875424, 5222.32109825724, 5317.335333083364, 5326.6537675050995, 5367.865803354947, 5368.8389348319615, 5736.483716465538, 5739.311282646067, 5761.683107429089, 5768.625039372428, 5775.451332123442, 5544.750486228129, 5644.209810626516, 5690.089585666274, 5824.388439213864, 5856.355800861902, 5373.946260537345, 5395.541378982791, 5416.729163541847, 5417.972910135463, 5420.24116623673, 5896.528277737771, 5923.49650279731, 5926.851828704317, 5927.110933359944, 5927.73325200367, 5496.258005169251, 5566.777328906879, 5600.047991841397, 5611.158326251095, 5728.341240757283, 5740.225069615632, 5786.276715410574, 5788.913166302509, 5790.536757059425, 5796.739146738407, 5718.971405143031, 5720.713964301768, 5723.599348045597, 5729.28383952006, 5767.769289228468, 5459.945400546023, 5700.37295897451, 5741.024025915604, 5765.567582431335, 5823.155642431823, 4421.602055814928, 4426.336049592523, 4432.601065904019, 4435.268180750205, 4472.798724057425, 3892.8637497687446, 3925.8822235332987, 3935.134270460494, 3956.287868183697, 3973.304671682444, 5434.8913021739345, 5478.45215478449, 5509.311336082989, 5530.336359636869, 5534.557235421156, 5796.0, 5832.620879642895, 5839.328477225174, 5846.824595262149, 5863.149843272701, 5224.50367215114, 5404.297441332602, 5523.143912693539, 5532.861678458071, 5533.529649979716, 4865.756712164449, 4908.9263661045115, 4909.361782968192, 4911.778969946361, 4960.627952903546, 6020.337762846083, 6023.6385816851125, 6025.3372129065765, 6028.186194863637, 6030.246219222598, 5533.280673512579, 5701.515371193495, 5760.596758226884, 5763.250777399079, 5846.329875880011, 6004.669743163993, 6015.849603759941, 6017.217761690952, 6017.488513476404, 6073.726682299219, 5699.715014249354, 5700.002499562477, 5703.714814259188, 5705.115707065412, 5709.229254050816, 5557.527610153183, 5736.799212027559, 5791.594588378773, 5795.0, 5802.448767367094, 5677.1768093627015, 5687.0616348302065, 5689.260396148474, 5689.26039614864, 5731.656100633975, 5633.0987042073075, 5674.801381951612, 5687.914681279702, 5694.345150307768, 5696.544276457957, 5419.674819510842, 5518.889622207453, 5523.696196709059, 5596.832095037155, 5598.972005140111, 5704.344000440003, 5719.142128680731, 5734.569907256889, 5744.569157313302, 5786.392428795084, 5569.359523654832, 5579.8884022320135, 5580.469855363734, 5584.106542953271, 5592.832215033555], "value": 5317.735705668427, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.2448877680959, 613.6440864298705, 622.0, 627.792828366639, 631.9620822750637, 43976.0, 45359.54640453603, 45535.544644553585, 45655.77172114139, 48822.755886220606, 46541.534584654146, 47175.76412117943, 47185.29222061663, 47274.05451890969, 47274.52725472744, 9014.639414423431, 9114.544272786366, 9128.680496182635, 9158.542072896362, 9160.0, 8940.19538241558, 9032.232260257853, 9046.411983221102, 9049.140331668514, 9049.728508144766, 9060.818783624323, 9093.454392736425, 9096.40873343234, 9108.407953483036, 9122.452652840817, 9046.276297896185, 9074.546272686339, 9078.591463384164, 9081.318901082444, 9082.318826088036, 8993.0, 9073.909260907372, 9077.954610226972, 9079.909200908038, 9086.681966131153, 43420.3486947696, 43461.34807977883, 43514.56485435169, 43529.564704353186, 43981.0, 3472.131967008248, 3686.55761308642, 3775.528058992626, 3980.800959951991, 4225.492940847087, 33440.16399590005, 34143.48784768231, 34458.31083378319, 34542.654573454194, 34546.13634659129, 3617.276544691046, 3722.274156539476, 3851.114243723959, 3873.2447172801317, 3930.6658933990643, 402564.0, 403642.0, 403701.0, 404012.0, 404072.0, 60328.0, 60587.09119363213, 60661.0900836479, 60710.69644651748, 60721.39278607246, 63618.68190659017, 63634.0, 63674.0, 63727.68136159382, 63790.36209637844, 17106.401275955282, 17359.56601084983, 17385.826141738675, 17389.130543473028, 17398.21708023142, 13635.318234088256, 13698.315084245747, 13709.245991470565, 13711.314434278247, 13726.519571815139, 14131.364088616037, 16712.582185445463, 16880.662386752443, 16882.324707011583, 16902.74645880313, 5010.997800439926, 5821.854453638693, 5858.326292476335, 5937.15948056104, 5961.76433837311, 1004.3672486333709, 1038.7718344151904, 1067.391586795519, 1110.3282514079028, 1198.574506050357, 32416.928024137596, 33563.32873342568, 33565.0, 33761.324773504886, 33795.66204337925, 1097.6981330134117, 1108.3128460354633, 1120.1542835159403, 1129.5086637312795, 1133.665568657248, 16858.409955651492, 17209.7418538722, 17441.825581744273, 17535.222124417545, 17850.285988560314, 607661.0, 609644.6624368125, 610949.0, 611059.0, 611565.0, 59866.40133598696, 59910.70044649749, 59919.10121348183, 59994.70002650046, 60024.69987650034, 1229.827824104626, 1339.4575197045212, 1344.9529266475822, 1388.9166650001032, 1392.6727219103705, 34032.0, 34938.82530587331, 34959.65040349666, 35047.0, 35110.94667159989, 52003.0, 52454.73772631113, 53020.0, 53036.73481632722, 53080.0, 467372.0, 469106.0, 469265.0, 470564.0, 471643.0, 34842.47736284009, 40890.79554602208, 41917.0, 42020.369694454, 45225.54774452213, 50111.74944125256, 50789.23816142687, 51192.74403627958, 51346.45960621187, 52096.21855672092, 40916.59083409128, 42074.7896260529, 43756.34365484457, 45221.54778452305, 46827.06345873132, 42781.0, 44020.55979440165, 44694.10611787681, 44912.32631510595, 44913.32630010487, 43778.12443751171, 44190.558094418644, 44730.55269447264, 44946.77526612346, 45115.323270150315, 38090.0, 38742.0, 39142.412863806494, 39994.0, 40234.597654023084, 29245.41509169762, 29258.561121583618, 29289.414211716074, 29677.99302634592, 29952.0], "value": 20977.212577486123, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.22770231834016, 387.517540661876, 394.4556512013423, 394.6271513345794, 400.6114069352727, 14447.0, 18837.905810470944, 19152.0, 20425.28511502098, 20429.387118386454, 14991.175485348302, 18180.727289090675, 18941.0, 19874.0, 20171.0, 5884.0, 6021.066734656135, 6027.789027384042, 6036.0644100164545, 6055.061465472859, 4297.269464191095, 5958.433948774859, 5998.700064996755, 6369.490440764727, 6770.5599136056, 6713.227978782429, 6816.522843400964, 6822.692978815965, 6864.691088900987, 6872.484563657721, 6467.805965821033, 6609.239937407212, 6758.425533829606, 6779.457643388541, 6801.931980680204, 5697.1739097831, 5753.453421924911, 5767.1349297605275, 5794.188813566104, 5797.130430435426, 24563.263102106965, 24581.508369832503, 25393.49213015748, 25728.614070788954, 25835.22494325173, 2408.0849277274524, 2458.30815257293, 2505.6742623459018, 2638.56463683492, 2650.628911952328, 15602.375904963788, 16260.837391626168, 16464.42374516886, 16999.83000169995, 17133.0, 2011.3463124484601, 2046.549759053006, 2095.298075144828, 2096.976417756872, 2112.207922029239, 82188.0, 98252.50873745585, 99909.99999999927, 101622.0, 102393.48803255935, 17578.648427031643, 18226.0, 18453.7231941521, 18582.0, 18816.81183188178, 32454.513182301813, 32890.50664240038, 33080.0, 33081.17297067593, 33197.33605327928, 10289.639862604772, 10431.113355364727, 10454.372737635766, 10600.469976501145, 10646.254762166574, 6848.178218613699, 8734.170253825876, 8799.384043117048, 8847.73456796297, 8998.280137588978, 8250.587470626564, 9026.097390261053, 9193.586288617029, 9377.859332110023, 9389.436633801994, 3559.618362555531, 3580.4629305604367, 3651.3457433309713, 3666.7616604920595, 3681.5214022177483, 945.3052006775152, 1001.7695929936156, 1009.5759780891896, 1039.7712503249272, 1075.833245846895, 19885.60228795416, 20517.58964820695, 20970.580588388148, 20971.8951405242, 21320.893395533232, 984.7045886234101, 1010.3988127064385, 1013.6857574152036, 1020.8417695257247, 1038.1642777563982, 11049.28179668319, 11560.364179970014, 11579.594714184963, 11584.189106762446, 11596.942015290038, 540052.0, 544997.0, 561531.0, 569410.0, 570794.0, 97942.0, 98407.0, 99250.0, 100996.0, 101369.0, 989.223459584223, 1000.5947591225565, 1007.5113569918584, 1031.8606988056517, 1036.4558606731375, 17546.561335966704, 17985.75178102737, 18363.449096527125, 18471.0, 18663.626727465646, 42639.36040959326, 43648.781756092285, 43922.121557569306, 44692.55307447014, 45209.77395113003, 61470.0, 62534.374656252854, 63844.68077659582, 64031.35968640254, 65473.34526654863, 8891.288696904361, 9045.592948317473, 9088.272938164799, 9134.22359099484, 9478.052194780466, 10701.464926753475, 12260.693482663004, 14240.857591423954, 14457.493987710379, 14459.13245205291, 12189.939050305047, 12206.938965305117, 12454.0, 12534.623961281177, 12744.808827867404, 12233.816492752794, 12238.08214383907, 12349.567765128177, 12568.560100396808, 12653.304068276333, 11927.701807454883, 12524.749505010031, 12549.372531373578, 12930.612081637566, 12944.676383090122, 8683.175098365646, 8976.82046359056, 9367.718968430958, 9480.383775054599, 9512.952435238056, 12226.69075014711, 12728.74542509163, 12863.935680321913, 12944.676383090498, 12977.610671679864], "value": 10628.276158819399, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.43626458755, 1491.0, 1502.0, 1676.2791999440244, 1766.7526546283523, 8278.254957053863, 8649.870251946219, 8661.61022753976, 8878.600462979166, 8886.0, 8692.217700406962, 8847.380683352168, 8850.468971861692, 8852.070532594065, 8855.203031727144, 2044.9386518404472, 2095.947601309972, 2162.545865368271, 2187.628103222448, 2191.1454532732246, 2284.2804516577226, 2287.4738810073695, 2289.175896677195, 2293.587154312223, 2294.541091781642, 2262.2421488801187, 2276.6243569810963, 2282.7945484906354, 2283.748787633359, 2284.0292875527916, 1975.1309423853531, 2054.5479994401207, 2116.4814620418097, 2121.1303365620174, 2201.52667176557, 2197.505561248722, 2210.049678638185, 2215.1582398688556, 2217.7227846519186, 2219.711437513129, 8926.776830579287, 8950.328725345626, 8950.462972221687, 8950.910490895138, 8954.955225223897, 5186.455422180692, 5296.9735151324385, 5304.708241046741, 5312.893742125175, 5323.121684921984, 3243.9026829195163, 3344.2642618623863, 3458.15275257561, 3466.480027995821, 3483.4948932404914, 1099.2140619457127, 1105.778844231149, 1113.2986218682179, 1114.899659030691, 1128.0524359538028, 13133.540326088636, 13152.539661111816, 13167.802482962763, 13184.406701698446, 13194.07641465098, 4470.2177119004045, 4570.54294570547, 4730.053989202104, 4756.143894099096, 4766.165920963817, 10926.399048052433, 10928.672139835817, 10942.069924056545, 10949.616763413242, 10959.397233152094, 5294.841154765363, 5297.311349524614, 5308.495692909245, 5308.522232999048, 5311.7344132793205, 4298.656107511391, 4724.503927087675, 4859.198232291703, 4876.5854902332985, 4937.901241975141, 4078.6940979426663, 4083.6324730773777, 4138.213739389506, 4148.315527937856, 4169.937450938239, 1161.4425075963584, 1211.0432758121162, 1253.6301790971686, 1275.9744805103846, 1289.5873320537337, 817.9897826184776, 855.2516548020482, 940.8541676040225, 971.7522031881825, 1018.8828284747201, 6398.072279519537, 6421.29365769765, 6565.409113179837, 6578.0, 6596.73613055482, 1002.8245057114975, 1004.7136566078779, 1007.5314978535016, 1038.2161468091542, 1068.6793961811425, 4129.463169787909, 4173.8956526087095, 4185.686073544496, 4200.0, 4212.347086201644, 10835.133189344833, 11133.443327833738, 11222.102231821435, 11225.270357426742, 11268.267562608404, 11136.832947505794, 11139.443027848574, 11146.498407571678, 11155.330680159217, 11175.273607215506, 7923.12845586985, 8175.427720059658, 8563.143685631387, 8693.869591956125, 8697.956510217535, 6301.117843502005, 6538.313477084837, 6565.29205345814, 6643.40209381158, 6704.396604305636, 10720.624778132727, 10749.838752418877, 10979.780404392028, 11005.944970275097, 11014.39420831862, 10628.627998020342, 10841.512131953923, 11033.889661103287, 11176.720581985515, 11200.047995920439, 7365.9631701841145, 7845.13703492604, 7996.680132794856, 8099.230573095547, 8170.0, 8748.518831464331, 8761.080086590817, 8786.604602792762, 8826.735197944072, 8827.382083254111, 7613.086429628259, 7856.135825059353, 7875.606219689107, 7890.408219383455, 7958.721444749406, 7647.732329368446, 7722.0, 7832.412569057229, 7870.921290787019, 7886.605669716377, 7190.20907700163, 7256.96371518139, 7308.086489188851, 7310.122785265802, 7323.670434830339, 5345.839624811262, 5364.865878353073, 5417.891642167214, 5497.8075767347955, 5589.441055894541, 5399.973000134974, 5407.83776486706, 5494.0, 5507.642003269774, 5518.586106041983], "value": 4719.806640085366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.4528520374549, 326.11134658056784, 328.0372107863421, 333.1803762743651, 336.50197707393056, 5263.76313065912, 5271.051210782058, 5272.710000949946, 5279.260903473512, 5284.2073688946675, 5160.741962901859, 5222.791088356471, 5223.843284701456, 5225.973870130644, 5230.091016778877, 3878.1661942682317, 4128.782009307261, 4131.442255295527, 4134.6692264618905, 4136.276151673445, 4070.409790580364, 4110.671146308288, 4135.338345864662, 4146.438917191081, 4150.128473020654, 4076.449679293302, 4081.163361510898, 4082.5713300103507, 4098.4057311689785, 4103.5280942691525, 3899.415087736834, 3928.292907276689, 4029.717919745619, 4097.815598298069, 4109.198706252282, 3542.6988705960025, 3558.5729712434413, 3565.964340356589, 3573.928521429557, 3576.2847430514003, 5108.216022267873, 5110.2590124431745, 5110.795568177268, 5117.744112794346, 5118.6416950813455, 4455.242608756486, 4550.0889626356675, 4658.277966915133, 4695.882602934954, 4700.858974230779, 5406.648108073001, 5462.0, 5475.890482190334, 5476.836649523416, 5487.64330318528, 3941.2708648899757, 3957.7229593928437, 3964.7224694271413, 3980.542237642679, 3988.641022308006, 5414.8917021659345, 5622.184783206412, 5708.0, 5712.971435142839, 5734.971325143348, 3949.1311911379453, 3963.8216280267457, 3965.5439624442984, 3965.92068158641, 3968.543617483969, 4005.939910901339, 4008.819603117867, 4010.2981978153707, 4014.759114453142, 4016.136530645925, 4446.6220371268155, 4601.907961840744, 4602.723836569817, 4619.722816631013, 4622.260438329851, 5502.724863756796, 5512.924979628934, 5512.932650256154, 5524.889502210013, 5526.0, 1396.9173890234947, 1412.3362019850858, 1416.0370947755418, 1416.7803990381508, 1418.4042702065158, 856.2935578148052, 905.8550631898864, 906.7959709065408, 923.6951805904035, 924.5977999570208, 4707.8587642370785, 4730.929036064462, 4740.715557066587, 4750.767139444456, 4757.35775670287, 4983.25251212321, 4992.400911890524, 5026.436517376164, 5028.270900719448, 5044.578602270857, 4711.199096153594, 4743.383360163157, 4747.976260118678, 4752.952470475251, 5082.974585127051, 2988.1782509809977, 3002.3695024045196, 3016.9547456788164, 3018.4868572342752, 3025.9106721580215, 5770.567207459458, 5785.363610002896, 5824.271966004249, 5841.474267315962, 5844.47399734026, 5547.750351234205, 5751.1373294006235, 5753.180941338797, 5769.826905192851, 5779.959607270647, 5794.275715535558, 5823.650580965071, 5825.349465992963, 5826.592138550345, 5831.94168058314, 5443.482869127427, 5712.0860662293835, 5713.685747283939, 5723.313202415737, 5723.484886360247, 5605.383407825055, 5607.8878422432135, 5642.153676948491, 5675.914861277001, 5675.971620141873, 5813.76744930198, 5815.709214539341, 5819.214406055297, 5832.533397328119, 5847.239858818326, 4003.5195776507007, 4084.7549147051272, 4094.9795251023556, 4188.204241194163, 4219.7046206765235, 5604.057869618483, 5635.436456354331, 5638.126090455986, 5646.604737668324, 5650.63270887391, 5479.726013699219, 5483.369412517612, 5489.819688766935, 5498.642588231752, 5508.980838544882, 5344.877575709144, 5420.186971954238, 5485.259489968803, 5486.890262194654, 5487.286652735119, 5395.946040539544, 5406.810761623325, 5410.3778065522965, 5415.1335786274, 5421.484958928895, 5309.389420216725, 5361.919571206513, 5554.1946417770005, 5557.805476808292, 5566.749496272759, 5424.186372044225, 5464.316960379952, 5470.058129364178, 5474.479924407175, 5480.451954804487], "value": 4152.905265703754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.2088765988236, 775.2557544757033, 785.5757890739001, 829.8298848736008, 842.3135144856944, 15474.0, 16870.409535666255, 16870.83129168708, 17208.82791172088, 18059.729104063448, 17023.148842557883, 17054.403095891645, 17173.398931037416, 17190.39833605824, 17236.13819309036, 674.0462245922015, 813.0893399392688, 814.9877751833727, 831.1895901496023, 831.9376046796484, 820.6553247636007, 842.3682238321259, 842.5787106446777, 842.6503001254472, 842.873568964654, 872.8516152254132, 880.106691707917, 880.6389380354057, 881.2245224202693, 881.6209030117047, 691.7855464805926, 833.9916600833975, 860.6729442811692, 863.8272345530887, 871.7428358634218, 631.9304876463585, 632.8607706304606, 633.0441034038589, 633.3255083336232, 633.4045996763028, 14875.033122847088, 14928.178950157737, 14931.850681493155, 14956.0, 14970.550883473514, 14685.265736713229, 14925.776113358308, 15038.248087595577, 15069.47256846005, 15124.0, 13780.931095344458, 13796.241206733626, 13883.514077007256, 14020.369083391271, 14297.0, 4540.704854184468, 4581.496035436099, 4633.930491042637, 4759.238521836524, 4772.47502774698, 82509.0, 83098.58450707768, 83300.0, 84349.15650843474, 84457.0, 11499.540018399337, 11633.59282425128, 11665.76668466626, 11697.181197316106, 11736.823947640793, 21113.683294750284, 21118.47203819917, 21123.78876211218, 21126.0, 21129.577408451743, 8559.752321981425, 8733.694320698745, 8825.470471771585, 8940.374173807772, 8964.910350896538, 4822.132016237041, 4905.632077594195, 4908.803647854046, 4934.309196712463, 4947.480514545993, 3478.2695633916655, 3624.075860655516, 3624.583172935146, 3632.328019316435, 3641.7997010164695, 651.563452486835, 654.6334052930374, 657.7697805767943, 663.6582160187507, 682.9897551536731, 578.4389142531737, 668.355037388922, 706.1067749297143, 725.4776560876161, 740.526063319476, 9679.225661947028, 9699.224062075016, 9898.851517227247, 9924.956186995321, 10015.649452269281, 619.8140557832633, 630.8675178212627, 633.9461145802659, 725.5356571794056, 728.6611725547646, 4732.668713190113, 4855.295982082649, 4919.581835543947, 4920.655554111249, 4927.77824997876, 121039.0, 122863.0, 125425.0, 126160.36919815342, 130101.0, 59408.0, 85986.0, 90323.0, 102127.0, 102305.48847255716, 91601.0, 93542.53228733812, 94290.0, 94881.0, 95461.0, 9508.144267016001, 9621.27840411958, 9682.273829462678, 9686.854697179408, 9780.804383912424, 56010.17913277683, 56164.43835561755, 57465.71267143638, 57938.4206157933, 58726.412735872094, 72991.63504182445, 75850.62074689592, 81583.0, 81892.59053704693, 82000.58999704964, 11393.544258229578, 11511.712207194887, 11602.245854019462, 11668.766624667289, 12186.025117990717, 12171.33057681836, 12322.199057061262, 12492.188007779465, 13626.863731362559, 14062.859371406565, 12171.573994910135, 12388.62834114978, 12393.690157746129, 12524.532202729804, 12572.622821315375, 12383.133180677267, 12488.812667810173, 12625.242485450903, 12748.68128296763, 13034.4134513949, 12240.387980600755, 12528.123031387715, 12584.93707531493, 12644.0, 12688.809667855174, 9906.127460688353, 10299.691009269734, 10454.529546170288, 10456.424896630759, 10787.460626968777, 9884.802303953737, 9963.25275604347, 10079.546420410952, 10138.391696498235, 10164.288499805238], "value": 7657.038066056041, "unit": "score"}], "name": "dromaeo_css", "value": 7499.873085803907, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 118.0, 176.0, 118.0, 116.0, 147.0, 116.0, 145.0, 118.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 159.0, 161.0, 160.0, 161.0, 164.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 125.0, 75.0, 76.0, 75.0, 74.0, 100.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 118.0, 84.0, 84.0, 84.0, 121.0, 83.0, 84.0, 121.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 53.0, 54.0, 52.0, 54.0, 54.0, 54.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 74.0, 74.0, 74.0, 75.0, 74.0, 75.0, 79.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 116.0, 119.0, 117.0, 120.0, 118.0, 117.0, 167.0, 135.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 162.0, 153.0, 159.0, 155.0, 153.0, 153.0, 157.0, 153.0, 152.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 66.0, 63.0, 61.0, 62.0, 63.0, 60.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1467.5999999999997, "alertThreshold": 2.0}]} 03:45:01 INFO - Return code: 0 03:45:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:45:01 INFO - # TBPL SUCCESS # 03:45:01 INFO - Running post-action listener: _resource_record_post_action 03:45:01 INFO - ##### 03:45:01 INFO - ##### Finished run-tests step (success) 03:45:01 INFO - ##### 03:45:01 INFO - Running post-run listener: _resource_record_post_run 03:45:01 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 90112; Write bytes: 568233984; Read time: 180; Write time: 476124 03:45:01 INFO - TinderboxPrint: CPU usage
13.0% 03:45:01 INFO - TinderboxPrint: I/O read bytes / time
90,112 / 180 03:45:01 INFO - TinderboxPrint: I/O write bytes / time
568,233,984 / 476,124 03:45:01 INFO - TinderboxPrint: CPU idle
8,262.5 (87.0%) 03:45:01 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 03:45:01 INFO - TinderboxPrint: Swap in / out
0 / 0 03:45:01 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 268951552; Read time: 0; Write time: 263928 03:45:01 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 90112; Write bytes: 298860544; Read time: 180; Write time: 212172 03:45:01 INFO - Running post-run listener: _upload_blobber_files 03:45:01 INFO - Blob upload gear active. 03:45:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:45:01 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:45:01 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:45:01 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:45:02 INFO - (blobuploader) - INFO - Open directory for files ... 03:45:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:45:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:45:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:02 INFO - (blobuploader) - INFO - Done attempting. 03:45:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:45:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:45:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:06 INFO - (blobuploader) - INFO - Done attempting. 03:45:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:45:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:07 INFO - (blobuploader) - INFO - Done attempting. 03:45:07 INFO - (blobuploader) - INFO - Iteration through files over. 03:45:07 INFO - Return code: 0 03:45:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:45:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:45:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cd1fd3b9d77cd953ce92ebcfed6d2db60a72fa18b8f000dd1d08ce210ce07fb77247aafcc30c658e08a279d744ead17679c2668e8ebd6f41c9b40bf2d0f4467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/841001f10056b95e969d80226d53db85a839a61924e2b3fcc08aa9ba2a38f24ca95f369a85ec1bc72b20080cf5f32d37229d57579348bb9e110fd1c4ac84862c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a5355e76d2fbb9f97d43503651cd7a525929443677312d7b62bd1e64b6ca89cdc4501804ae4ee76229146b03f51a5cb140941bc350aa42faec2692ccff187dde"} 03:45:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:45:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:45:07 INFO - Contents: 03:45:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cd1fd3b9d77cd953ce92ebcfed6d2db60a72fa18b8f000dd1d08ce210ce07fb77247aafcc30c658e08a279d744ead17679c2668e8ebd6f41c9b40bf2d0f4467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/841001f10056b95e969d80226d53db85a839a61924e2b3fcc08aa9ba2a38f24ca95f369a85ec1bc72b20080cf5f32d37229d57579348bb9e110fd1c4ac84862c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a5355e76d2fbb9f97d43503651cd7a525929443677312d7b62bd1e64b6ca89cdc4501804ae4ee76229146b03f51a5cb140941bc350aa42faec2692ccff187dde"} 03:45:07 INFO - Running post-run listener: copy_logs_to_upload_dir 03:45:07 INFO - Copying logs to upload dir... 03:45:07 INFO - mkdir: /builds/slave/test/build/upload/logs 03:45:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.759645 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-09 03:45:06.928448) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:45:06.932602) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 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/8cd1fd3b9d77cd953ce92ebcfed6d2db60a72fa18b8f000dd1d08ce210ce07fb77247aafcc30c658e08a279d744ead17679c2668e8ebd6f41c9b40bf2d0f4467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/841001f10056b95e969d80226d53db85a839a61924e2b3fcc08aa9ba2a38f24ca95f369a85ec1bc72b20080cf5f32d37229d57579348bb9e110fd1c4ac84862c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a5355e76d2fbb9f97d43503651cd7a525929443677312d7b62bd1e64b6ca89cdc4501804ae4ee76229146b03f51a5cb140941bc350aa42faec2692ccff187dde"} 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.011324 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/8cd1fd3b9d77cd953ce92ebcfed6d2db60a72fa18b8f000dd1d08ce210ce07fb77247aafcc30c658e08a279d744ead17679c2668e8ebd6f41c9b40bf2d0f4467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/841001f10056b95e969d80226d53db85a839a61924e2b3fcc08aa9ba2a38f24ca95f369a85ec1bc72b20080cf5f32d37229d57579348bb9e110fd1c4ac84862c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a5355e76d2fbb9f97d43503651cd7a525929443677312d7b62bd1e64b6ca89cdc4501804ae4ee76229146b03f51a5cb140941bc350aa42faec2692ccff187dde"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:45:06.961796) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:45:06.962186) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468056753.180012-902594415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:45:07.049065) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:45:07.049614) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:45:07.050187) ========= ========= Total master_lag: 0.19 =========