builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1475471728.65 results: success (0) buildid: 20161002192227 builduid: 2100e2a4a088463f8b9643954c6cdce6 revision: a85ec3655e36d4c4cbadb23e08fdb742b0315989 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:28.646930) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:28.647395) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:28.647667) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:29.486400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:29.486794) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-02 22:15:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.7M=0s 2016-10-02 22:15:30 (36.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121364 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:29.622139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:29.622453) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018496 ========= master_lag: 0.31 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:29.949867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-02 22:15:29.950238) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a85ec3655e36d4c4cbadb23e08fdb742b0315989 --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 a85ec3655e36d4c4cbadb23e08fdb742b0315989 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-02 22:15:30,438 truncating revision to first 12 chars 2016-10-02 22:15:30,438 Setting DEBUG logging. 2016-10-02 22:15:30,438 attempt 1/10 2016-10-02 22:15:30,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a85ec3655e36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-02 22:15:31,745 unpacking tar archive at: autoland-a85ec3655e36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.732646 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-02 22:15:31.696368) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:31.697629) ========= script_repo_revision: a85ec3655e36d4c4cbadb23e08fdb742b0315989 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:31.698043) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:31.698377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-02 22:15:31.707307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-02 22:15:31.707714) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:15:32 INFO - MultiFileLogger online at 20161002 22:15:32 in /builds/slave/test 22:15:32 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 22:15:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:15:32 INFO - {'append_to_log': False, 22:15:32 INFO - 'base_work_dir': '/builds/slave/test', 22:15:32 INFO - 'blob_upload_branch': 'Autoland', 22:15:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:15:32 INFO - 'branch': 'Autoland', 22:15:32 INFO - 'buildbot_json_path': 'buildprops.json', 22:15:32 INFO - 'config_files': ('talos/linux_config.py',), 22:15:32 INFO - 'default_actions': ('clobber', 22:15:32 INFO - 'read-buildbot-config', 22:15:32 INFO - 'download-and-extract', 22:15:32 INFO - 'populate-webroot', 22:15:32 INFO - 'create-virtualenv', 22:15:32 INFO - 'install', 22:15:32 INFO - 'run-tests'), 22:15:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:15:32 INFO - 'download_minidump_stackwalk': True, 22:15:32 INFO - 'download_symbols': 'ondemand', 22:15:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:15:32 INFO - 'tooltool.py': '/tools/tooltool.py', 22:15:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:15:32 INFO - '/tools/misc-python/virtualenv.py')}, 22:15:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:15:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:15:32 INFO - 'installer_path': 'installer.exe', 22:15:32 INFO - 'log_level': 'info', 22:15:32 INFO - 'log_name': 'talos', 22:15:32 INFO - 'log_to_console': True, 22:15:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:15:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:15:32 INFO - 'opt_config_files': (), 22:15:32 INFO - 'pip_index': False, 22:15:32 INFO - 'sps_profile': False, 22:15:32 INFO - 'sps_profile_interval': 0, 22:15:32 INFO - 'suite': 'dromaeojs', 22:15:32 INFO - 'system_bits': '32', 22:15:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:15:32 INFO - 'title': 'talos-linux64-ix-069', 22:15:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:15:32 INFO - 'use_talos_json': True, 22:15:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:15:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:15:32 INFO - 'work_dir': 'build'} 22:15:32 INFO - [mozharness: 2016-10-03 05:15:32.290748Z] Running clobber step. 22:15:32 INFO - Running pre-action listener: _resource_record_pre_action 22:15:32 INFO - Running main action method: clobber 22:15:32 INFO - rmtree: /builds/slave/test/build 22:15:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:15:33 INFO - Running post-action listener: _resource_record_post_action 22:15:33 INFO - [mozharness: 2016-10-03 05:15:33.274039Z] Finished clobber step (success) 22:15:33 INFO - [mozharness: 2016-10-03 05:15:33.274141Z] Running read-buildbot-config step. 22:15:33 INFO - Running pre-action listener: _resource_record_pre_action 22:15:33 INFO - Running main action method: read_buildbot_config 22:15:33 INFO - Using buildbot properties: 22:15:33 INFO - { 22:15:33 INFO - "project": "", 22:15:33 INFO - "product": "firefox", 22:15:33 INFO - "script_repo_revision": "production", 22:15:33 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 22:15:33 INFO - "repository": "", 22:15:33 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 22:15:33 INFO - "buildid": "20161002192227", 22:15:33 INFO - "pgo_build": "True", 22:15:33 INFO - "basedir": "/builds/slave/test", 22:15:33 INFO - "buildnumber": 287, 22:15:33 INFO - "slavename": "talos-linux64-ix-069", 22:15:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:15:33 INFO - "platform": "ubuntu64_hw", 22:15:33 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 22:15:33 INFO - "branch": "autoland", 22:15:33 INFO - "repo_path": "integration/autoland", 22:15:33 INFO - "revision": "a85ec3655e36d4c4cbadb23e08fdb742b0315989", 22:15:33 INFO - "stage_platform": "linux64-pgo", 22:15:33 INFO - "builduid": "2100e2a4a088463f8b9643954c6cdce6", 22:15:33 INFO - "slavebuilddir": "test" 22:15:33 INFO - } 22:15:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 22:15:33 INFO - Running post-action listener: _resource_record_post_action 22:15:33 INFO - [mozharness: 2016-10-03 05:15:33.276611Z] Finished read-buildbot-config step (success) 22:15:33 INFO - [mozharness: 2016-10-03 05:15:33.276700Z] Running download-and-extract step. 22:15:33 INFO - Running pre-action listener: _resource_record_pre_action 22:15:33 INFO - Running main action method: download_and_extract 22:15:33 INFO - mkdir: /builds/slave/test/build/tests 22:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:33 INFO - trying https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:15:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:15:33 INFO - Downloaded 1361 bytes. 22:15:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:15:33 INFO - Using the following test package requirements: 22:15:33 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:15:33 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:15:33 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:15:33 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'jsshell-linux-x86_64.zip'], 22:15:33 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:15:33 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:15:33 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:15:33 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:15:33 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:15:33 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:15:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:15:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:15:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:15:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:15:33 INFO - Fetch https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:15:34 INFO - Http code: 200 22:15:34 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 22:15:34 INFO - x-amz-cf-id: kFocIRX4x9cGJGOhAiIaf8XNSLDk5JrLl3Xh2Tu9F8MduJT5dO3oqQ== 22:15:34 INFO - x-amz-version-id: d.uotclpYZqNuZSR4oDCbhk.xU1KNwjs 22:15:34 INFO - Expected file size: 21988945 22:15:34 INFO - Obtained file size: 21988945 22:15:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:15:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:15:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:15:38 INFO - Fetch https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:15:39 INFO - Http code: 200 22:15:39 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 22:15:39 INFO - x-amz-cf-id: hc7ufIhMTzbIXAshy7QlJqF7deYToEoojMimAs6E2SQo4w7fIrXWbQ== 22:15:39 INFO - x-amz-version-id: d.uotclpYZqNuZSR4oDCbhk.xU1KNwjs 22:15:39 INFO - Expected file size: 21988945 22:15:39 INFO - Obtained file size: 21988945 22:15:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 22:15:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:15:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:15:44 INFO - Http code: 200 22:15:44 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 22:15:44 INFO - x-amz-cf-id: QWFOt8Yr2AAf1oLYmOna4Q8_tWwDIvf9sXHjioTdok9z26BtcRrTnQ== 22:15:44 INFO - x-amz-version-id: ZkkAw9mO3jmZ2kf3NbIdtZgGsAuobdHG 22:15:44 INFO - Expected file size: 14414067 22:15:44 INFO - Obtained file size: 14414067 22:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:45 INFO - trying https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:15:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:15:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:15:46 INFO - Downloaded 59764404 bytes. 22:15:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:15:46 INFO - mkdir: /builds/slave/test/properties 22:15:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:15:46 INFO - Writing to file /builds/slave/test/properties/build_url 22:15:46 INFO - Contents: 22:15:46 INFO - build_url:https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:15:47 INFO - Running post-action listener: _resource_record_post_action 22:15:47 INFO - Running post-action listener: set_extra_try_arguments 22:15:47 INFO - [mozharness: 2016-10-03 05:15:47.100150Z] Finished download-and-extract step (success) 22:15:47 INFO - [mozharness: 2016-10-03 05:15:47.100378Z] Running populate-webroot step. 22:15:47 INFO - Running pre-action listener: _resource_record_pre_action 22:15:47 INFO - Running main action method: populate_webroot 22:15:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:15:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:15:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:15:47 INFO - '--tppagecycles', 22:15:47 INFO - '7'], 22:15:47 INFO - 'tests': ['tsvgm']}}, 22:15:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:15:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:15:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:15:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:15:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['damp', 'tps']}, 22:15:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'tests': ['damp', 'tps']}, 22:15:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['dromaeo_dom']}, 22:15:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:15:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['basic_compositor_video']}, 22:15:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:15:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'other-e10s': {'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['a11yr', 22:15:47 INFO - 'ts_paint', 22:15:47 INFO - 'tpaint', 22:15:47 INFO - 'sessionrestore', 22:15:47 INFO - 'sessionrestore_no_auto_restore', 22:15:47 INFO - 'tabpaint']}, 22:15:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['tsvgx', 22:15:47 INFO - 'tsvgr_opacity', 22:15:47 INFO - 'tart', 22:15:47 INFO - 'tscrollx', 22:15:47 INFO - 'cart']}, 22:15:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:15:47 INFO - 'tsvgr_opacity', 22:15:47 INFO - 'tart', 22:15:47 INFO - 'tscrollx', 22:15:47 INFO - 'cart']}, 22:15:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'talos_options': ['--disable-e10s'], 22:15:47 INFO - 'tests': ['tp5o']}, 22:15:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'tests': ['tp5o']}, 22:15:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'talos_options': ['--disable-e10s', 22:15:47 INFO - '--xperf_path', 22:15:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:47 INFO - 'tests': ['tp5n']}, 22:15:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:47 INFO - 'talos_options': ['--xperf_path', 22:15:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:47 INFO - 'tests': ['tp5n']}}, 22:15:47 INFO - 'talos.zip': {'path': '', 22:15:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:15:47 INFO - Running post-action listener: _resource_record_post_action 22:15:47 INFO - [mozharness: 2016-10-03 05:15:47.117608Z] Finished populate-webroot step (success) 22:15:47 INFO - [mozharness: 2016-10-03 05:15:47.117698Z] Running create-virtualenv step. 22:15:47 INFO - Running pre-action listener: _resource_record_pre_action 22:15:47 INFO - Running main action method: create_virtualenv 22:15:47 INFO - Creating virtualenv /builds/slave/test/build/venv 22:15:47 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 22:15:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:15:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:15:47 INFO - Using real prefix '/usr' 22:15:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:15:47 INFO - Installing distribute.............................................................................................................................................................................................done. 22:15:49 INFO - Installing pip.................done. 22:15:49 INFO - Return code: 0 22:15:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:15:49 INFO - Reading from file tmpfile_stdout 22:15:49 INFO - Output received: 22:15:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:15:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:15:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:49 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15:49 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 22:15:49 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 22:15:49 INFO - Using env: {'DISPLAY': ':0', 22:15:49 INFO - 'HOME': '/home/cltbld', 22:15:49 INFO - 'LANG': 'en_US.UTF-8', 22:15:49 INFO - 'LANGUAGE': 'en_US:en', 22:15:49 INFO - 'LOGNAME': 'cltbld', 22:15:49 INFO - 'MAIL': '/var/mail/cltbld', 22:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:49 INFO - 'MOZ_NO_REMOTE': '1', 22:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:49 INFO - 'NO_EM_RESTART': '1', 22:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:49 INFO - 'PWD': '/builds/slave/test', 22:15:49 INFO - 'SHELL': '/bin/bash', 22:15:49 INFO - 'SHLVL': '1', 22:15:49 INFO - 'TERM': 'linux', 22:15:49 INFO - 'TMOUT': '86400', 22:15:49 INFO - 'USER': 'cltbld', 22:15:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:49 INFO - '_': '/tools/buildbot/bin/python'} 22:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:50 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 22:15:50 INFO - Cleaning up... 22:15:50 INFO - Return code: 0 22:15:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:15:50 INFO - Using env: {'DISPLAY': ':0', 22:15:50 INFO - 'HOME': '/home/cltbld', 22:15:50 INFO - 'LANG': 'en_US.UTF-8', 22:15:50 INFO - 'LANGUAGE': 'en_US:en', 22:15:50 INFO - 'LOGNAME': 'cltbld', 22:15:50 INFO - 'MAIL': '/var/mail/cltbld', 22:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:50 INFO - 'MOZ_NO_REMOTE': '1', 22:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:50 INFO - 'NO_EM_RESTART': '1', 22:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:50 INFO - 'PWD': '/builds/slave/test', 22:15:50 INFO - 'SHELL': '/bin/bash', 22:15:50 INFO - 'SHLVL': '1', 22:15:50 INFO - 'TERM': 'linux', 22:15:50 INFO - 'TMOUT': '86400', 22:15:50 INFO - 'USER': 'cltbld', 22:15:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:50 INFO - '_': '/tools/buildbot/bin/python'} 22:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:50 INFO - Downloading/unpacking psutil>=3.1.1 22:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:15:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:52 INFO - Installing collected packages: psutil 22:15:52 INFO - Running setup.py install for psutil 22:15:52 INFO - building 'psutil._psutil_linux' extension 22:15:52 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 22:15:52 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 22:15:52 INFO - building 'psutil._psutil_posix' extension 22:15:52 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 22:15:52 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 22:15:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:52 INFO - Successfully installed psutil 22:15:52 INFO - Cleaning up... 22:15:52 INFO - Return code: 0 22:15:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:15: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 mozsystemmonitor==0.3 22:15:52 INFO - Using env: {'DISPLAY': ':0', 22:15:52 INFO - 'HOME': '/home/cltbld', 22:15:52 INFO - 'LANG': 'en_US.UTF-8', 22:15:52 INFO - 'LANGUAGE': 'en_US:en', 22:15:52 INFO - 'LOGNAME': 'cltbld', 22:15:52 INFO - 'MAIL': '/var/mail/cltbld', 22:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:52 INFO - 'MOZ_NO_REMOTE': '1', 22:15:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:52 INFO - 'NO_EM_RESTART': '1', 22:15:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:52 INFO - 'PWD': '/builds/slave/test', 22:15:52 INFO - 'SHELL': '/bin/bash', 22:15:52 INFO - 'SHLVL': '1', 22:15:52 INFO - 'TERM': 'linux', 22:15:52 INFO - 'TMOUT': '86400', 22:15:52 INFO - 'USER': 'cltbld', 22:15:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:52 INFO - '_': '/tools/buildbot/bin/python'} 22:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:15:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:15:55 INFO - Installing collected packages: mozsystemmonitor 22:15:55 INFO - Running setup.py install for mozsystemmonitor 22:15:55 INFO - Successfully installed mozsystemmonitor 22:15:55 INFO - Cleaning up... 22:15:55 INFO - Return code: 0 22:15:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', '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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:15: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 jsonschema==2.5.1 22:15:55 INFO - Using env: {'DISPLAY': ':0', 22:15:55 INFO - 'HOME': '/home/cltbld', 22:15:55 INFO - 'LANG': 'en_US.UTF-8', 22:15:55 INFO - 'LANGUAGE': 'en_US:en', 22:15:55 INFO - 'LOGNAME': 'cltbld', 22:15:55 INFO - 'MAIL': '/var/mail/cltbld', 22:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:55 INFO - 'MOZ_NO_REMOTE': '1', 22:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:55 INFO - 'NO_EM_RESTART': '1', 22:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:55 INFO - 'PWD': '/builds/slave/test', 22:15:55 INFO - 'SHELL': '/bin/bash', 22:15:55 INFO - 'SHLVL': '1', 22:15:55 INFO - 'TERM': 'linux', 22:15:55 INFO - 'TMOUT': '86400', 22:15:55 INFO - 'USER': 'cltbld', 22:15:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:55 INFO - '_': '/tools/buildbot/bin/python'} 22:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:55 INFO - Downloading/unpacking jsonschema==2.5.1 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:15:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15:57 INFO - Downloading functools32-3.2.3-2.tar.gz 22:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:15:57 INFO - warning: no files found matching '*.txt' 22:15:57 INFO - no previously-included directories found matching 'build' 22:15:57 INFO - no previously-included directories found matching 'dist' 22:15:57 INFO - no previously-included directories found matching '.git*' 22:15:57 INFO - Installing collected packages: jsonschema, functools32 22:15:57 INFO - Running setup.py install for functools32 22:15:57 INFO - warning: no files found matching '*.txt' 22:15:57 INFO - no previously-included directories found matching 'build' 22:15:57 INFO - no previously-included directories found matching 'dist' 22:15:57 INFO - no previously-included directories found matching '.git*' 22:15:57 INFO - Successfully installed jsonschema functools32 22:15:57 INFO - Cleaning up... 22:15:57 INFO - Return code: 0 22:15:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:58 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15:58 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:15:58 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 functools32==3.2.3-2 22:15:58 INFO - Using env: {'DISPLAY': ':0', 22:15:58 INFO - 'HOME': '/home/cltbld', 22:15:58 INFO - 'LANG': 'en_US.UTF-8', 22:15:58 INFO - 'LANGUAGE': 'en_US:en', 22:15:58 INFO - 'LOGNAME': 'cltbld', 22:15:58 INFO - 'MAIL': '/var/mail/cltbld', 22:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:58 INFO - 'MOZ_NO_REMOTE': '1', 22:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:58 INFO - 'NO_EM_RESTART': '1', 22:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:58 INFO - 'PWD': '/builds/slave/test', 22:15:58 INFO - 'SHELL': '/bin/bash', 22:15:58 INFO - 'SHLVL': '1', 22:15:58 INFO - 'TERM': 'linux', 22:15:58 INFO - 'TMOUT': '86400', 22:15:58 INFO - 'USER': 'cltbld', 22:15:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:58 INFO - '_': '/tools/buildbot/bin/python'} 22:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:15:58 INFO - Cleaning up... 22:15:58 INFO - Return code: 0 22:15:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:15:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15:58 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:15:58 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 22:15:58 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 22:15:58 INFO - Using env: {'DISPLAY': ':0', 22:15:58 INFO - 'HOME': '/home/cltbld', 22:15:58 INFO - 'LANG': 'en_US.UTF-8', 22:15:58 INFO - 'LANGUAGE': 'en_US:en', 22:15:58 INFO - 'LOGNAME': 'cltbld', 22:15:58 INFO - 'MAIL': '/var/mail/cltbld', 22:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:58 INFO - 'MOZ_NO_REMOTE': '1', 22:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:58 INFO - 'NO_EM_RESTART': '1', 22:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:58 INFO - 'PWD': '/builds/slave/test', 22:15:58 INFO - 'SHELL': '/bin/bash', 22:15:58 INFO - 'SHLVL': '1', 22:15:58 INFO - 'TERM': 'linux', 22:15:58 INFO - 'TMOUT': '86400', 22:15:58 INFO - 'USER': 'cltbld', 22:15:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:58 INFO - '_': '/tools/buildbot/bin/python'} 22:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:58 INFO - Downloading/unpacking blobuploader==1.2.4 22:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:16:00 INFO - Downloading blobuploader-1.2.4.tar.gz 22:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:16:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:16:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16:00 INFO - Downloading docopt-0.6.1.tar.gz 22:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:16:01 INFO - Installing collected packages: blobuploader, requests, docopt 22:16:01 INFO - Running setup.py install for blobuploader 22:16:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:16:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:16:01 INFO - Running setup.py install for requests 22:16:01 INFO - Running setup.py install for docopt 22:16:01 INFO - Successfully installed blobuploader requests docopt 22:16:01 INFO - Cleaning up... 22:16:01 INFO - Return code: 0 22:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:16:01 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 22:16:01 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 22:16:01 INFO - Using env: {'DISPLAY': ':0', 22:16:01 INFO - 'HOME': '/home/cltbld', 22:16:01 INFO - 'LANG': 'en_US.UTF-8', 22:16:01 INFO - 'LANGUAGE': 'en_US:en', 22:16:01 INFO - 'LOGNAME': 'cltbld', 22:16:01 INFO - 'MAIL': '/var/mail/cltbld', 22:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:01 INFO - 'MOZ_NO_REMOTE': '1', 22:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:01 INFO - 'NO_EM_RESTART': '1', 22:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:01 INFO - 'PWD': '/builds/slave/test', 22:16:01 INFO - 'SHELL': '/bin/bash', 22:16:01 INFO - 'SHLVL': '1', 22:16:01 INFO - 'TERM': 'linux', 22:16:01 INFO - 'TMOUT': '86400', 22:16:01 INFO - 'USER': 'cltbld', 22:16:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:01 INFO - '_': '/tools/buildbot/bin/python'} 22:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:01 INFO - Running setup.py (path:/tmp/pip-5but4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:01 INFO - Running setup.py (path:/tmp/pip-i1RpXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:02 INFO - Running setup.py (path:/tmp/pip-oupuIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:02 INFO - Running setup.py (path:/tmp/pip-fkFKEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:02 INFO - Running setup.py (path:/tmp/pip-0Wr8lA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:02 INFO - Running setup.py (path:/tmp/pip-evtHkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:02 INFO - Running setup.py (path:/tmp/pip-xI6PmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:02 INFO - Running setup.py (path:/tmp/pip-lvR7cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:02 INFO - Running setup.py (path:/tmp/pip-kKmn4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:02 INFO - Running setup.py (path:/tmp/pip-Yidjce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:02 INFO - Running setup.py (path:/tmp/pip-rVvGyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:02 INFO - Running setup.py (path:/tmp/pip-lW_D_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:02 INFO - Running setup.py (path:/tmp/pip-tgB1ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:02 INFO - Running setup.py (path:/tmp/pip-7RtOYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:02 INFO - Running setup.py (path:/tmp/pip-7c_oCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:03 INFO - Running setup.py (path:/tmp/pip-f60iFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:03 INFO - Running setup.py (path:/tmp/pip-OHXGfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:16:03 INFO - Running setup.py install for manifestparser 22:16:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozcrash 22:16:03 INFO - Running setup.py install for mozdebug 22:16:03 INFO - Running setup.py install for mozdevice 22:16:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozfile 22:16:03 INFO - Running setup.py install for mozhttpd 22:16:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozinfo 22:16:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozInstall 22:16:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:16:03 INFO - Running setup.py install for mozleak 22:16:04 INFO - Running setup.py install for mozlog 22:16:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for moznetwork 22:16:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for mozprocess 22:16:04 INFO - Running setup.py install for mozprofile 22:16:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for mozrunner 22:16:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Running setup.py install for mozscreenshot 22:16:04 INFO - Running setup.py install for moztest 22:16:04 INFO - Running setup.py install for mozversion 22:16:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:16:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:16:04 INFO - Cleaning up... 22:16:04 INFO - Return code: 0 22:16:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:04 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:16:04 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 22:16:04 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 22:16:04 INFO - Using env: {'DISPLAY': ':0', 22:16:04 INFO - 'HOME': '/home/cltbld', 22:16:04 INFO - 'LANG': 'en_US.UTF-8', 22:16:04 INFO - 'LANGUAGE': 'en_US:en', 22:16:04 INFO - 'LOGNAME': 'cltbld', 22:16:04 INFO - 'MAIL': '/var/mail/cltbld', 22:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:04 INFO - 'MOZ_NO_REMOTE': '1', 22:16:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:04 INFO - 'NO_EM_RESTART': '1', 22:16:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:04 INFO - 'PWD': '/builds/slave/test', 22:16:04 INFO - 'SHELL': '/bin/bash', 22:16:04 INFO - 'SHLVL': '1', 22:16:04 INFO - 'TERM': 'linux', 22:16:04 INFO - 'TMOUT': '86400', 22:16:04 INFO - 'USER': 'cltbld', 22:16:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:04 INFO - '_': '/tools/buildbot/bin/python'} 22:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:05 INFO - Running setup.py (path:/tmp/pip-TtE_io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 INFO - Running setup.py (path:/tmp/pip-vL1wUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 INFO - Running setup.py (path:/tmp/pip-vR7kk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 INFO - Running setup.py (path:/tmp/pip-Exp6hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:05 INFO - Running setup.py (path:/tmp/pip-VC6cKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 INFO - Running setup.py (path:/tmp/pip-81jJun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 INFO - Running setup.py (path:/tmp/pip-3R10GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 INFO - Running setup.py (path:/tmp/pip-3iizWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:05 INFO - Running setup.py (path:/tmp/pip-ZI81xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Running setup.py (path:/tmp/pip-t9d7Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:05 INFO - Running setup.py (path:/tmp/pip-jlmXbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:05 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)) 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:05 INFO - Running setup.py (path:/tmp/pip-ULButr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 INFO - Running setup.py (path:/tmp/pip-plF6bD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 INFO - Running setup.py (path:/tmp/pip-8LAHAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 INFO - Running setup.py (path:/tmp/pip-ETj6mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:06 INFO - Running setup.py (path:/tmp/pip-mZIJDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:06 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)) 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:06 INFO - Running setup.py (path:/tmp/pip-NONMGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 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)) 22:16:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:06 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 22:16:06 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 22:16:06 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 22:16:06 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 22:16:06 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 22:16:06 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 22:16:08 INFO - Downloading blessings-1.6.tar.gz 22:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:16:08 INFO - Installing collected packages: blessings 22:16:08 INFO - Running setup.py install for blessings 22:16:08 INFO - Successfully installed blessings 22:16:08 INFO - Cleaning up... 22:16:08 INFO - Return code: 0 22:16:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:08 INFO - Reading from file tmpfile_stdout 22:16:08 INFO - Current package versions: 22:16:08 INFO - argparse == 1.2.1 22:16:08 INFO - blessings == 1.6 22:16:08 INFO - blobuploader == 1.2.4 22:16:08 INFO - docopt == 0.6.1 22:16:08 INFO - functools32 == 3.2.3-2 22:16:08 INFO - jsonschema == 2.5.1 22:16:08 INFO - manifestparser == 1.1 22:16:08 INFO - mozInstall == 1.12 22:16:08 INFO - mozcrash == 0.17 22:16:08 INFO - mozdebug == 0.1 22:16:08 INFO - mozdevice == 0.48 22:16:08 INFO - mozfile == 1.2 22:16:08 INFO - mozhttpd == 0.7 22:16:08 INFO - mozinfo == 0.9 22:16:08 INFO - mozleak == 0.1 22:16:08 INFO - mozlog == 3.4 22:16:08 INFO - moznetwork == 0.27 22:16:08 INFO - mozprocess == 0.23 22:16:08 INFO - mozprofile == 0.28 22:16:08 INFO - mozrunner == 6.12 22:16:08 INFO - mozscreenshot == 0.1 22:16:08 INFO - mozsystemmonitor == 0.3 22:16:08 INFO - moztest == 0.7 22:16:08 INFO - mozversion == 1.4 22:16:08 INFO - psutil == 3.1.1 22:16:08 INFO - requests == 1.2.3 22:16:08 INFO - wsgiref == 0.1.2 22:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:08 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:16:08 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 22:16:08 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 22:16:08 INFO - Using env: {'DISPLAY': ':0', 22:16:08 INFO - 'HOME': '/home/cltbld', 22:16:08 INFO - 'LANG': 'en_US.UTF-8', 22:16:08 INFO - 'LANGUAGE': 'en_US:en', 22:16:08 INFO - 'LOGNAME': 'cltbld', 22:16:08 INFO - 'MAIL': '/var/mail/cltbld', 22:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:08 INFO - 'MOZ_NO_REMOTE': '1', 22:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:08 INFO - 'NO_EM_RESTART': '1', 22:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:08 INFO - 'PWD': '/builds/slave/test', 22:16:08 INFO - 'SHELL': '/bin/bash', 22:16:08 INFO - 'SHLVL': '1', 22:16:08 INFO - 'TERM': 'linux', 22:16:08 INFO - 'TMOUT': '86400', 22:16:08 INFO - 'USER': 'cltbld', 22:16:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:08 INFO - '_': '/tools/buildbot/bin/python'} 22:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 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)) 22:16:08 INFO - Cleaning up... 22:16:08 INFO - Return code: 0 22:16:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:09 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 0x7fee233a9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xccb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd98790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', '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 22:16:09 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 22:16:09 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 22:16:09 INFO - Using env: {'DISPLAY': ':0', 22:16:09 INFO - 'HOME': '/home/cltbld', 22:16:09 INFO - 'LANG': 'en_US.UTF-8', 22:16:09 INFO - 'LANGUAGE': 'en_US:en', 22:16:09 INFO - 'LOGNAME': 'cltbld', 22:16:09 INFO - 'MAIL': '/var/mail/cltbld', 22:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:09 INFO - 'MOZ_NO_REMOTE': '1', 22:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:09 INFO - 'NO_EM_RESTART': '1', 22:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:09 INFO - 'PWD': '/builds/slave/test', 22:16:09 INFO - 'SHELL': '/bin/bash', 22:16:09 INFO - 'SHLVL': '1', 22:16:09 INFO - 'TERM': 'linux', 22:16:09 INFO - 'TMOUT': '86400', 22:16:09 INFO - 'USER': 'cltbld', 22:16:09 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:09 INFO - '_': '/tools/buildbot/bin/python'} 22:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:16:09 INFO - Cleaning up... 22:16:09 INFO - Return code: 0 22:16:09 INFO - Running post-action listener: _resource_record_post_action 22:16:09 INFO - Running post-action listener: _start_resource_monitoring 22:16:09 INFO - Starting resource monitoring. 22:16:09 INFO - [mozharness: 2016-10-03 05:16:09.251313Z] Finished create-virtualenv step (success) 22:16:09 INFO - [mozharness: 2016-10-03 05:16:09.251834Z] Running install step. 22:16:09 INFO - Running pre-action listener: _resource_record_pre_action 22:16:09 INFO - Running main action method: install 22:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:09 INFO - Reading from file tmpfile_stdout 22:16:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:16:09 INFO - Reading from file tmpfile_stdout 22:16:09 INFO - Output received: 22:16:09 INFO - Usage: mozinstall [options] installer 22:16:09 INFO - Options: 22:16:09 INFO - -h, --help show this help message and exit 22:16:09 INFO - -d DEST, --destination=DEST 22:16:09 INFO - Directory to install application into. [default: 22:16:09 INFO - "/builds/slave/test"] 22:16:09 INFO - --app=APP Application being installed. [default: firefox] 22:16:09 INFO - mkdir: /builds/slave/test/build/application 22:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:16:26 INFO - Reading from file tmpfile_stdout 22:16:26 INFO - Output received: 22:16:26 INFO - /builds/slave/test/build/application/firefox/firefox 22:16:26 INFO - Running post-action listener: _resource_record_post_action 22:16:26 INFO - [mozharness: 2016-10-03 05:16:26.588821Z] Finished install step (success) 22:16:26 INFO - [mozharness: 2016-10-03 05:16:26.589071Z] Running run-tests step. 22:16:26 INFO - Running pre-action listener: _resource_record_pre_action 22:16:26 INFO - Running main action method: run_tests 22:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:16:26 INFO - Python 2.7.3 22:16:26 INFO - Return code: 0 22:16:26 INFO - grabbing minidump binary from tooltool 22:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:26 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 0xd95930>, '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 0xd96830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd96cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:16:26 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 22:16:26 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 22:16:26 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 22:16:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:16:26 INFO - Return code: 0 22:16:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:16:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:16:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:16:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:16:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:16:26 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/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:16:26 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/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 22:16:26 INFO - Using env: {'DISPLAY': ':0', 22:16:26 INFO - 'HOME': '/home/cltbld', 22:16:26 INFO - 'LANG': 'en_US.UTF-8', 22:16:26 INFO - 'LANGUAGE': 'en_US:en', 22:16:26 INFO - 'LOGNAME': 'cltbld', 22:16:26 INFO - 'MAIL': '/var/mail/cltbld', 22:16:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:16:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:26 INFO - 'MOZ_NO_REMOTE': '1', 22:16:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:26 INFO - 'NO_EM_RESTART': '1', 22:16:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:26 INFO - 'PWD': '/builds/slave/test', 22:16:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:16:26 INFO - 'SHELL': '/bin/bash', 22:16:26 INFO - 'SHLVL': '1', 22:16:26 INFO - 'TERM': 'linux', 22:16:26 INFO - 'TMOUT': '86400', 22:16:26 INFO - 'USER': 'cltbld', 22:16:26 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097', 22:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:26 INFO - '_': '/tools/buildbot/bin/python'} 22:16:26 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/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 22:16:26 INFO - mozversion application_buildid: 20161002192227 22:16:26 INFO - mozversion application_changeset: a85ec3655e36d4c4cbadb23e08fdb742b0315989 22:16:26 INFO - mozversion application_display_name: Nightly 22:16:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:16:26 INFO - mozversion application_name: Firefox 22:16:26 INFO - mozversion application_remotingname: firefox 22:16:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:16:26 INFO - mozversion application_vendor: Mozilla 22:16:26 INFO - mozversion application_version: 52.0a1 22:16:26 INFO - mozversion platform_buildid: 20161002192227 22:16:26 INFO - mozversion platform_changeset: a85ec3655e36d4c4cbadb23e08fdb742b0315989 22:16:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:16:26 INFO - mozversion platform_version: 52.0a1 22:16:26 INFO - using testdate: 1475471786 22:16:26 INFO - actual date: 1475471786 22:16:26 INFO - starting webserver on 'localhost:58357' 22:16:26 INFO - SUITE-START | Running 2 tests 22:16:26 INFO - TEST-START | dromaeo_css 22:16:26 INFO - operating with platform_type : linux_ 22:16:26 INFO - Initialising browser for dromaeo_css test... 22:16:26 INFO - TEST-INFO | started process 20597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAGHbu5/profile http://localhost:58357/getInfo.html) 22:16:31 INFO - PROCESS | 20597 | __metrics Screen width/height:1600/1200 22:16:31 INFO - PROCESS | 20597 | colorDepth:24 22:16:31 INFO - PROCESS | 20597 | Browser inner width/height: 1024/697 22:16:31 INFO - PROCESS | 20597 | __metrics 22:16:31 INFO - PROCESS | 20597 | 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" 22:16:31 INFO - PROCESS | 20597 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:16:32 INFO - PROCESS | 20597 | console.error: PushService: 22:16:32 INFO - PROCESS | 20597 | stateChangeProcessEnqueue: Error transitioning state 22:16:32 INFO - PROCESS | 20597 | UnknownError 22:16:33 INFO - TEST-INFO | 20597: exit 0 22:16:33 INFO - Browser initialized. 22:16:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:16:33 INFO - TEST-INFO | started process 20678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAGHbu5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:45 INFO - PROCESS | 20678 | RSS: Main: 173649920 22:16:45 INFO - PROCESS | 20678 | 22:17:36 INFO - PROCESS | 20678 | 1475471856351 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] 22:19:46 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58357/tests/dromaeo/cssquery-ext.html) 22:19:47 INFO - PROCESS | 20678 | RSS: Main: 306622464 22:19:47 INFO - PROCESS | 20678 | 22:22:50 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-ext.html (next: http://localhost:58357/tests/dromaeo/cssquery-jquery.html) 22:22:50 INFO - PROCESS | 20678 | RSS: Main: 282632192 22:22:50 INFO - PROCESS | 20678 | 22:25:52 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58357/tests/dromaeo/cssquery-mootools.html) 22:25:53 INFO - PROCESS | 20678 | RSS: Main: 219336704 22:25:53 INFO - PROCESS | 20678 | 22:28:55 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58357/tests/dromaeo/cssquery-prototype.html) 22:28:55 INFO - PROCESS | 20678 | RSS: Main: 231157760 22:28:55 INFO - PROCESS | 20678 | 22:31:58 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58357/tests/dromaeo/cssquery-yui.html) 22:31:59 INFO - PROCESS | 20678 | RSS: Main: 225767424 22:31:59 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | Cycle 1(1): loaded http://localhost:58357/tests/dromaeo/cssquery-yui.html (next: http://localhost:58357/tests/dromaeo/cssquery-dojo.html) 22:35:02 INFO - PROCESS | 20678 | RSS: Main: 308600832 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | __start_tp_report 22:35:02 INFO - PROCESS | 20678 | _x_x_mozilla_page_load 22:35:02 INFO - PROCESS | 20678 | _x_x_mozilla_page_load_details 22:35:02 INFO - PROCESS | 20678 | |i|pagename|runs| 22:35:02 INFO - PROCESS | 20678 | |0;dojo.html;3079.2917628945343;3096.071178646407;3108.4715598348284;3127.4683303838347;3151.9684803151963;5275.446078161795;5350.5452036576935;5399.568034557231;5515.283013208283;5527.115661494162;5386.8922621547545;5420.756065977031;5488.807891723791;5520.6687598744;5576.219329293902;4886.951130488703;4929.728864912428;4936.358273424467;4959.950400496003;5095.159298715726;5098.311727916739;5106.055379754739;5110.1312776827845;5114.641975061747;5116.2069879168785;4872.9756351218375;4917.311576379309;4918.803247870081;5107.080725469414;5132.28148059272;4887.437944636376;4910.607151427877;4938.9753051234875;4997.900041999176;5017.297578339036;4953.2074868020945;5000.374953130859;5004.5495905368325;5020.246962955549;5025.12060389434;5421.83734487966;5446.945530544684;5450.454954504517;5453.291072160594;5456.454354564551;5622.100463925753;5850.765969361263;5852.56105792063;5855.76576936922;5918.053111502139;5716.542676585863;5752.9424705753245;5771.451712087387;5775.1337299405;5877.882442351171;5363.892722145574;5404.405515393303;5415.83752487426;5420.891582168374;5423.783048678088;5882.941170588325;5918.201042859245;5977.820665380045;5981.252343457068;5999.160117583542;5599.7480113394995;5614.214010038598;5646.802361917313;5647;5655.123455864347;5780.855478612984;5782.797602083991;5784.132380142928;5837.270341207349;5851.590388672837;5824.650520968756;5829.5044921181325;5837.211976383134;5838.474537291664;5840.737166827503;5686.630369026;5735.254416925856;5767.538596912237;5821.79623713168;5828.271466066742;4509.616682581952;4511.774411279423;4558.247889098262;4573.131105090022;4573.702709323949;3967.1272320089533;3993.4608827807874;4010.7794071325775;4016.196760647882;4051.554329023805;5345.1447768356875;5360.91958620621;5394.056040192951;5447.618666693294;5512.6968016758665;5840.678762668094;5896.292444906553;5899.911501327483;5968.104784282393;5974.312954010257;5477.67133971963;5492.340919089655;5496.862578435571;5503.807366742145;5515.503604675599;4944.653874228841;4987.925181122286;5089.083964886282;5102.642815002914;5157.974210128926;5932.614380065368;5977.432143946318;5985.431384018511;5990.430909063632;5992.041273396236;5764.913526297193;5799.76800927975;5808.651480911159;5817.331006934088;5826.300843898759;5987;5988.6706231157705;5993.130996005642;5994.580379373402;6001.129836173817;5742.023855944583;5784.884302313846;5801.709914504174;5886.852828679317;5896.6756828374855;5933.584649074523;5936.554758393224;5936.762529498772;5937.2578427696535;5990.880182396415;5772.855678608068;5786.479216870502;5786.884262314814;5789.1895134680335;5792.333881603669;5572.60991730575;5594.41258667834;5620.718964051863;5641.492265696107;5755.942440575708;5667;5727.513161381329;5733.4266573344;5736.4550367715;5738.282714660668;5702.401247868914;5716.142578613241;5716.885662286648;5721.284839395075;5729.255196824386;5452.154915988028;5584.916226256691;5602.075657516627;5605.523530499953;5607.719614019365 22:35:02 INFO - PROCESS | 20678 | |1;ext.html;531.7394476706413;558.4331903118334;563.7744902039184;627.7614506487537;639.5011890725236;31647.841760791194;42475;42516;42521;47797.28304075433;44630.33054504185;44885.32672009923;44984.77507612466;45022.774886125604;45038.774806125926;8818.603162857655;8976.955115224448;8978.551072446384;8979.14698103679;8979.820403591924;8761.08008659088;8805.471671699719;8854.601542930552;8860.955695221515;8863.512506812123;8864.379493435463;8886.911130888706;8934.553272336358;8939.150780675825;8939.597718102668;8733.257673097762;8859.867101993475;8869.866951995726;8873.245774109208;8880.200781929658;8834.469931804113;8880.822383552357;8897.288216942628;8905.243054340388;8905.910940890637;41238.1752364954;41247.175056499;41575.1684966302;41645.583544164474;41820.790896045626;2578.1878708206855;2602.1803132013356;2672.1449136276206;2866.6130072440164;3108.844557772125;31624.841875790706;31732.841335793404;32253.838730806197;33823.830880845686;33829.83085084559;3940.2513522430645;3959.208158368309;3962.366021436585;3975;4118.773467459288;329559;333389;333682.99999999756;333802;334952;58556.7072164645;58613;58912.70543647254;58987.41012589905;59047.40952590505;53863.730681346344;61302.693486532284;61311;61491;61515;13844.238566878917;15578.688426231412;15889.728821694236;15962.12208328553;15984.200789960689;11646.708832279091;11876.524939002344;11881.702957426134;12169.634910952685;12171;16971.30286971293;19452.416427507196;19463;19551.315703950295;19650.606987860163;5372.785088596491;6461;6536.411722944958;6709.228438729511;6847.93152068473;877.925376343018;952.623713633111;959.3811991265734;989;1051.9631812886512;30919.934827777062;32729.67270327314;32972.1515970124;33040.50439243413;33296;978.8678528398575;1028.6142696488816;1032.4940779018227;1036.0468369100445;1056.287006270764;16522.42171523991;17281.827181728022;17330.133493325535;17390.82609173892;17577.824221757874;518381;520161;520967;522319;525272;57465.71267143638;57854.13218801721;57873;58016;58023;1172.6377923560974;1218.585680868509;1229.274727910537;1234.8147777833217;1244.1788419642992;30456.84771576128;31367.84316078405;31803.840980794947;32209.51685724669;32279;50277.24584131168;50554.99999999853;50560.494395055575;50639;50671.74664126656;428610.283382998;431189;431485;431807;432138;33741.49387759136;34494.48258276078;39841.80079099586;42735.1452970945;43367.56632433635;46534.30198547092;47200.7639961798;47390.52609473999;47775.76112119556;48169;38980.80509597434;39922;40090.39864402095;41075.58924410837;41386.58613413827;39559;39740.40389394104;40525;40544.1891162181;41890.58109418867;36570.085747856516;36616.81691591525;37123.25753484969;37131.25737485289;37356.252874941805;33333.83333083416;35523.64476355203;36332.63667363292;36910.63089369072;37099.44350834793;27795.30511737142;28220.85889570539;28228.71771282261;28256.717432825408;28287.575686365133 22:35:02 INFO - PROCESS | 20678 | |2;jquery.html;390.2994125544647;390.53916378673154;398.12214067980096;402.88316388247404;416.704140060557;14206.502772402968;17349.653006939854;17691.82308176918;19409;19409.611807763853;18087.72868406975;18116.728249076274;18245.817541824577;18369;18523;5556.638818476807;5585;5727.570432217601;5758.596898217126;5761.251037365137;4139.523954745197;5705.372409035002;5776.971115144418;5786.710664466781;5789.739461724212;4161.888903291422;6071.635701857903;6335.461485773715;6377.681115944208;6412.647304398256;6099.115628233882;6292.87414251717;6340.619562826199;6400.199975003125;6414.807555773335;5586.217929489874;5600.915986260209;5602.187682785973;5608.831735047955;5609.4293597792275;21906.823510590875;22648.773512264997;22708.54582908349;22910.083596656114;23182.76817231823;2248;2355.8704271265074;2387.7612238776155;2586.107792811477;2941.558766185068;13589.252591107484;13817;14377.640558986004;14392.712145757027;14448.422063117463;1823;1857.359211072178;1872.2510995601733;1879.9718004229946;1888.688366419539;81083.5945820273;81955.59022204851;82233.58883205605;82926;83453.58273208656;9450.952745236229;9727.173190278905;9747.512624368754;10298.897011029943;10572.682819515427;12996.285204313665;14841.257937103317;14918.776218356734;14928.25358732059;14943.551693449213;7291.781246562611;7573.962130189314;7773.378129749606;7935.484193527403;7981.920180798234;7135.82160445993;7317.41460683144;7541.170471248159;7625.263620825755;7626.733064342721;6418.261899881573;6421.404013752221;6440.903386449207;6549.803505894831;6752.291009444036;1878.5773201029933;1901.8478521718507;1902.144035184145;1931.8357939575294;1986.5728868293386;748.8389996150854;838.2120806442047;852.1989330029876;920.8848893888264;979.9910184122663;17500.299988000337;17641.7353739694;17821.376251831123;18683.439496815117;18991;857.5883575883486;882.1311008656468;889.3552174673439;897.699270744308;948.4972964328916;9760.658376956773;10565.524551395205;10604.681859544226;10665.045616937196;10712.371195425614;457450;463659;466010;467837;472975;80958;81614.59192703999;82384.58807705923;83272.58363708142;84415;1007.1489591295343;1055.261317078033;1065.9093977011885;1066.5253961986841;1085.8751243607087;16648.83351166473;17132.743008854628;17541.736873947157;17564.82435175683;17583.64832703313;39557.40663890102;40283.59716402798;41150.79424602858;42058.15883682248;44172.51514606161;55689.16466253091;55690.72154639201;56925.43074569367;56940.43059569517;58864;8013.15861834499;8015.198480151938;8267.29727973129;8403.663853445794;8550.57247137653;11316.434178290889;12527.123101382816;12620.747585048432;12708.110432269652;13437;11255;11413.60052398162;11474.789706833706;11872.168948173545;11927.343996080299;9911.851322230317;10342.276040677081;10769.784604308026;11248.606298779503;11621.12841536905;6234.251889773256;11535.885310965825;11537.423128843691;11636.53453861836;11726.179167458196;7265.963670181615;7410.296021877913;7571.848563028819;7779.027621547307;8542.572871356531;9783.315167938175;11079.279846809932;11162.497687603915;11325.773484530428;11755.471003804678 22:35:02 INFO - PROCESS | 20678 | |3;mootools.html;1307.1372893890023;1439.4745917740029;1503.9924800375998;1602.3510478256303;1766.9028203448804;7582.393408527319;8613.698520551781;8934.374593778439;8962.058983806704;9202.539873006357;8524.573771311441;8594.570271486433;8596.355273354508;8601.784955376119;8601.870971935426;2313.7223533176;2319.1071437496516;2333.6266197408418;2333.871637059961;2347.6243800991847;2306.423394151462;2347.6595893595418;2371.27676058802;2372.8813559321966;2374.3589230907646;2307.3193407944614;2314.7685231476885;2320.2227253869974;2368.3723813189517;2372.50177462733;2306.1351993002622;2327.7323107842644;2337.4857531343077;2340.33300509354;2345.6129738593113;2280.635098384267;2283.668868014145;2284.097781376364;2286.588414085464;2291.8968646410954;8686.261667758248;8901.688440904602;9068.909310906873;9138.360314777969;9139.177474027369;5095.541401273866;5293;5294.602904782157;5298.258243845865;5305.283786688825;3209.3741720364537;3421.7091547218515;3435.9312813743836;3445.879394221215;3478.3217272631846;1063.8936106389376;1100.147385776022;1100.9394483303415;1113.565709373345;1118.4799068433217;12617.558385456465;12721.618351449379;12775.361231938366;12864.356782160854;12893.806592901208;4270.145970805851;4496.1906856765445;4507.684462087623;4594.609458196091;4638.0955713636195;9520.143187113194;9562.187214086742;9578.56896439662;9622.13400793932;9637.084476974676;4958.677685950402;4960.255961605788;4961.2806143109765;4966.503349665077;4979.800807967713;4116.464859568297;4227.42929704486;4435.822567097344;4516.774161291923;4595.563421474954;3699.2231631357176;3729.776213427203;3816.8473261069803;3825.7236944018264;3878.185581028016;1190.8213767934878;1203.181836351272;1204.9939750301191;1210.84864391951;1221.8533775946767;805.275252272959;871;897.7396554998977;907.5462268865567;908.5684299957468;5600.467955544217;5610.87782443513;5941.257342832146;5978.730957106854;6181.072839074175;948.3266880514774;952.9666461673808;954.1031430455349;957.5164541906432;999.0708640963828;3739.5325584301963;3940.8817735467983;3941;4018.1561872007205;4039.171969746195;11135.721606959729;11603.013743831869;11660.76678466426;11662.94168529152;11691.707707307385;11382.089432845352;11508.654740357802;11518.654440366803;11528.942355288338;11530.538778448936;8064.717734879251;8116.715914943066;8137.145599712064;8138.877916831252;8158.796030099176;5506.559475242051;5510.3938566758425;5661.150827375926;5684.317881854204;5811.331696854747;10093.293469457361;10107.444090575089;10113.949430252802;10206.081452669296;10245.282830201815;10071.949640251752;10223.437710925971;10249.436281004617;10284.897151028394;10409.42748148859;6555.901661474986;6733.93266067333;6740.090087838275;7328;7439;7845.411594130577;8368;8632.525211113449;8687.131286871263;8883.289336852908;7516.962415187889;7570.772876813704;7665.156832748282;7722.111957125003;7790.649420776192;7281.162666293444;7324.816879578053;7511.436642251743;7534.095908491016;7606.8098297543;6838.555493892881;7045.753398631024;7049.224585295511;7060.858782824212;7063.929360706327;4568.200564901195;4666.113438446684;4754.572088511912;4762.9523704762505;4849.2968519565175;4662.440507139029;4784.258439941815;4827.106985207748;4850.733209673502;5024.623153263593 22:35:02 INFO - PROCESS | 20678 | |4;prototype.html;328.5941861800677;332.38176990797115;339.23164033464195;340.1055224758884;344.8706734974385;5101.872453188663;5193.558547523456;5195.168772996321;5318.175682769167;5324.680519168853;5064.189729643258;5210.270562121296;5226.268322434862;5248.658837175581;5253.159494480884;4156.958430415687;4157.729747566398;4166.500019997605;4166.64583510402;4182.142660754538;4073.8370465181356;4109.815058322382;4187.989764912843;4190.203861266365;4206.917575799968;4054.6553542948886;4070.4911886014247;4083.4895638045246;4085.5710150434224;4086.7752273624938;4076.4496792932873;4084.6323830855217;4094.3653733671326;4101.138760860223;4120.196561670476;3619.2218672985173;3631.4371272452813;3633.8728144514816;3650.8174591270595;3652.4521321801676;5008.499150084999;5059.342285502861;5070.594352451795;5098.719570423626;5108.2593024011685;4599.862004139881;4618.838340658061;4629.213033784265;4630.490646028934;4653.115907977473;5420.945790542083;5433.293671822637;5435.565154787608;5443.319585051869;5479.068558345051;3744.326021316162;3893.552241492236;3930.567637559866;3938.409238614202;3944.585818489075;5608.738033942371;5609.214709940612;5609.80365687199;5651.5761317900915;5683.914741278884;3980.2238563480423;4036.0425193780993;4036.59634036594;4041.6766658667234;4056.59434056592;3985.780782056957;4044.838206471708;4065.2682517146604;4088.7342322748927;4097.590240975878;4615.16926953145;4619.538046195354;4620.260758278727;4634.37435946143;4639;5432.239486471897;5438;5443.863903402367;5488.588355873246;5510.504054635102;1308.358904136985;1329.8936085113169;1357.545222350504;1374.6975665353607;1400.7198560287984;943.9575219115156;954.3081266082035;962.259060523407;965.7730433348157;972.5331840716495;5213.165893457029;5216.556592689588;5226.529612334908;5251.579873610102;5267.183586544092;5541.612087153861;5555.666660000413;5558.277423934863;5566.888662226814;5584.804531841366;5019.322391477177;5205.791768329224;5208.374995000624;5241.9213711794355;5250.947490525123;2634.3150780796745;2647.430802377498;2650.748178923038;2653.6152257922495;2665.7067722550505;5107.646473684476;5110.4634013428795;5129.589632829365;5140.331756871583;5153.974230128901;4696.299627714078;5041.672291301127;5054.721990290569;5070.721110338893;5091.7016160879475;5058.620603454756;5059.9121188944555;5087;5089.829339251919;5106.974465127651;4951.628627852998;4972.801087956441;4996.225585034325;5018.874528136826;5044.192929131468;4582.450105987169;4865.9270110947655;4868.707877527218;4869.003708479507;4902.436219834622;5033.370828646419;5041.739565108723;5047.394312682355;5075.274406701591;5079.984003199301;3687.4837522747375;3714.684251838624;3731.197792474631;3769.679577235856;3770.490983717163;4763.499832517536;4877.80488780459;4921.704697718148;5063.873403164803;5077.695338279715;4726.740029298284;4741.217699079752;4835.564799168092;4860.854174374775;4874.246829940997;4779.928301075417;4784.229834961086;4821;4826.6621336506105;4836.758162091811;4658.627309815274;4734;4792.5926296263815;4793.257045158006;4795.808167673254;4845.3216549682365;4849.127157111613;4849.199882019428;4864.36763220779;4913.70517768935;4826.927596086131;4830.903381932271;4852.369192005017;4877.487863774394;4891.6575839692305 22:35:02 INFO - PROCESS | 20678 | |5;yui.html;623.7037407231566;680.9829754256144;688.1741909708348;692.407991167552;707.6002058836758;13717.176969381831;14498.202598857059;14555.126692398437;14617.196054217027;14623.78064329036;14264.286785660728;14290.571282861505;14311.642208944782;14314.928425357859;14387.568372948803;646.3891622416794;657.9834156228615;770.2874840772291;780.1145699630931;783.9608019598998;623.1431781300712;639.0765344077786;639.3350915048344;639.4820195641522;639.8624295776407;841.8653015517518;843.0431460292551;843.9156084391542;844.0588743550958;844.252836239926;679.4089142446054;695.8712638161957;798.7523867601062;838.1450920061534;839.0770152831897;609.9420555047263;611.8562137897591;612.7671484835779;613.3406587918006;613.5183880653666;14302.070365426214;14327.068740531831;14341.92829035848;14362.569122926328;14370.353334099991;12141.757164856654;12195.45120469572;12224.511019559208;12573.685657858536;12651.683707907285;10298.227632927561;10441.164706823436;10486.37081775096;10676.51955661997;11554.306741595532;4260.190563792869;4344.543822898613;4351.49957754859;4510.458744950594;4525.185466616008;78087;79997.20002799956;80268.19731802725;80740;81567.59216203941;10009.399436033862;10175.694729157989;10184.388936663823;10221.591136354611;10268.178545716324;17771.733423998652;17773.822261777215;17780.46658600244;17782.91108544449;17790;7909;7950.721724739722;8003.279704826594;8067.0722866871065;8095.878561821577;4247.405363249148;4387.2103021455805;4419.1382680377665;4518.796654150571;4527.298268768281;3396.218869659943;3511.841967111486;3517.2086280586664;3517.613062563116;3547.113221694576;575.4906907386909;580.7183515994739;583.9182514447981;585.2362666719886;607.3440684061203;495.67780942387736;527.6095689189972;590.8877313310458;603.9275286965592;642.2036674523557;8427.620757065946;8480.787980300542;8518.829623407497;8699.217070463563;8850.208140840323;632.8544434779934;634.964798704228;647.7311915555086;666.5534092158266;668.214847554122;3843.802043200539;3922.1371298314325;3922.4704664870446;4006.959930400717;4229.471316085489;124081;124556;129952;131666.41667916565;132691;103026;104336;104439;105193;105597.47201263944;58424.12363814546;59889.700551496964;64606.35393646004;91578;94336.52831735935;7204.56772593646;7211.495195336276;7364.66858991336;7473.663685134074;7537.2462753724185;54715.72642136923;55237.7238113823;55856;56320.15519767125;57172.71413642905;76937;81780.18219817964;82956.23237787874;83990;84491;9363.578638961128;9418.95290523543;9701.563429645825;10424.478776061318;10456.163506919276;12360.938195308965;12375.938120309702;12430.751384972431;12463.189892656948;13238.338083096;10953.233273670767;11160.218784684994;11183.832242516532;11368.374739389414;11680.591179308683;11278.548858045586;11630.127740419672;11679.182457227913;11696.88303116958;12005.75988480208;11479.25384849982;11563.595274165364;11687.532498700297;11832.585859494877;12116.575919842764;9434.820647419072;9594;9746.415215087118;9777.217822574035;9777.85333219988;9396.248300135836;9739.9026009739;9884.209263258777;9896.356736812084;10096.16104354946 22:35:02 INFO - PROCESS | 20678 | __end_tp_report 22:35:02 INFO - PROCESS | 20678 | __start_cc_report 22:35:02 INFO - PROCESS | 20678 | _x_x_mozilla_cycle_collect,1182 22:35:02 INFO - PROCESS | 20678 | __end_cc_report 22:35:02 INFO - PROCESS | 20678 | __startTimestamp1475472902456__endTimestamp 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | ------- Summary: start ------- 22:35:02 INFO - PROCESS | 20678 | Number of tests: 6 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#0] dojo.html Cycles:165 Average:5420.22 Median:5607.72 stddev:607.55 (10.8%) stddev-sans-first:580.99 22:35:02 INFO - PROCESS | 20678 | Values: 3079.3 3096.1 3108.5 3127.5 3152.0 5275.4 5350.5 5399.6 5515.3 5527.1 5386.9 5420.8 5488.8 5520.7 5576.2 4887.0 4929.7 4936.4 4960.0 5095.2 5098.3 5106.1 5110.1 5114.6 5116.2 4873.0 4917.3 4918.8 5107.1 5132.3 4887.4 4910.6 4939.0 4997.9 5017.3 4953.2 5000.4 5004.5 5020.2 5025.1 5421.8 5446.9 5450.5 5453.3 5456.5 5622.1 5850.8 5852.6 5855.8 5918.1 5716.5 5752.9 5771.5 5775.1 5877.9 5363.9 5404.4 5415.8 5420.9 5423.8 5882.9 5918.2 5977.8 5981.3 5999.2 5599.7 5614.2 5646.8 5647.0 5655.1 5780.9 5782.8 5784.1 5837.3 5851.6 5824.7 5829.5 5837.2 5838.5 5840.7 5686.6 5735.3 5767.5 5821.8 5828.3 4509.6 4511.8 4558.2 4573.1 4573.7 3967.1 3993.5 4010.8 4016.2 4051.6 5345.1 5360.9 5394.1 5447.6 5512.7 5840.7 5896.3 5899.9 5968.1 5974.3 5477.7 5492.3 5496.9 5503.8 5515.5 4944.7 4987.9 5089.1 5102.6 5158.0 5932.6 5977.4 5985.4 5990.4 5992.0 5764.9 5799.8 5808.7 5817.3 5826.3 5987.0 5988.7 5993.1 5994.6 6001.1 5742.0 5784.9 5801.7 5886.9 5896.7 5933.6 5936.6 5936.8 5937.3 5990.9 5772.9 5786.5 5786.9 5789.2 5792.3 5572.6 5594.4 5620.7 5641.5 5755.9 5667.0 5727.5 5733.4 5736.5 5738.3 5702.4 5716.1 5716.9 5721.3 5729.3 5452.2 5584.9 5602.1 5605.5 5607.7 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#1] ext.html Cycles:180 Average:59174.24 Median:41610.38 stddev:115323.95 (277.2%) stddev-sans-first:115563.41 22:35:02 INFO - PROCESS | 20678 | Values: 531.7 558.4 563.8 627.8 639.5 31647.8 42475.0 42516.0 42521.0 47797.3 44630.3 44885.3 44984.8 45022.8 45038.8 8818.6 8977.0 8978.6 8979.1 8979.8 8761.1 8805.5 8854.6 8861.0 8863.5 8864.4 8886.9 8934.6 8939.2 8939.6 8733.3 8859.9 8869.9 8873.2 8880.2 8834.5 8880.8 8897.3 8905.2 8905.9 41238.2 41247.2 41575.2 41645.6 41820.8 2578.2 2602.2 2672.1 2866.6 3108.8 31624.8 31732.8 32253.8 33823.8 33829.8 3940.3 3959.2 3962.4 3975.0 4118.8 329559.0 333389.0 333683.0 333802.0 334952.0 58556.7 58613.0 58912.7 58987.4 59047.4 53863.7 61302.7 61311.0 61491.0 61515.0 13844.2 15578.7 15889.7 15962.1 15984.2 11646.7 11876.5 11881.7 12169.6 12171.0 16971.3 19452.4 19463.0 19551.3 19650.6 5372.8 6461.0 6536.4 6709.2 6847.9 877.9 952.6 959.4 989.0 1052.0 30919.9 32729.7 32972.2 33040.5 33296.0 978.9 1028.6 1032.5 1036.0 1056.3 16522.4 17281.8 17330.1 17390.8 17577.8 518381.0 520161.0 520967.0 522319.0 525272.0 57465.7 57854.1 57873.0 58016.0 58023.0 1172.6 1218.6 1229.3 1234.8 1244.2 30456.8 31367.8 31803.8 32209.5 32279.0 50277.2 50555.0 50560.5 50639.0 50671.7 428610.3 431189.0 431485.0 431807.0 432138.0 33741.5 34494.5 39841.8 42735.1 43367.6 46534.3 47200.8 47390.5 47775.8 48169.0 38980.8 39922.0 40090.4 41075.6 41386.6 39559.0 39740.4 40525.0 40544.2 41890.6 36570.1 36616.8 37123.3 37131.3 37356.3 33333.8 35523.6 36332.6 36910.6 37099.4 27795.3 28220.9 28228.7 28256.7 28287.6 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#2] jquery.html Cycles:180 Average:27895.89 Median:2278.11 stddev:76744.14 (3368.8%) stddev-sans-first:76931.64 22:35:02 INFO - PROCESS | 20678 | Values: 390.3 390.5 398.1 402.9 416.7 14206.5 17349.7 17691.8 19409.0 19409.6 18087.7 18116.7 18245.8 18369.0 18523.0 5556.6 5585.0 5727.6 5758.6 5761.3 4139.5 5705.4 5777.0 5786.7 5789.7 4161.9 6071.6 6335.5 6377.7 6412.6 6099.1 6292.9 6340.6 6400.2 6414.8 5586.2 5600.9 5602.2 5608.8 5609.4 21906.8 22648.8 22708.5 22910.1 23182.8 2248.0 2355.9 2387.8 2586.1 2941.6 13589.3 13817.0 14377.6 14392.7 14448.4 1823.0 1857.4 1872.3 1880.0 1888.7 81083.6 81955.6 82233.6 82926.0 83453.6 9451.0 9727.2 9747.5 10298.9 10572.7 12996.3 14841.3 14918.8 14928.3 14943.6 7291.8 7574.0 7773.4 7935.5 7981.9 7135.8 7317.4 7541.2 7625.3 7626.7 6418.3 6421.4 6440.9 6549.8 6752.3 1878.6 1901.8 1902.1 1931.8 1986.6 748.8 838.2 852.2 920.9 980.0 17500.3 17641.7 17821.4 18683.4 18991.0 857.6 882.1 889.4 897.7 948.5 9760.7 10565.5 10604.7 10665.0 10712.4 457450.0 463659.0 466010.0 467837.0 472975.0 80958.0 81614.6 82384.6 83272.6 84415.0 1007.1 1055.3 1065.9 1066.5 1085.9 16648.8 17132.7 17541.7 17564.8 17583.6 39557.4 40283.6 41150.8 42058.2 44172.5 55689.2 55690.7 56925.4 56940.4 58864.0 8013.2 8015.2 8267.3 8403.7 8550.6 11316.4 12527.1 12620.7 12708.1 13437.0 11255.0 11413.6 11474.8 11872.2 11927.3 9911.9 10342.3 10769.8 11248.6 11621.1 6234.3 11535.9 11537.4 11636.5 11726.2 7266.0 7410.3 7571.8 7779.0 8542.6 9783.3 11079.3 11162.5 11325.8 11755.5 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#3] mootools.html Cycles:180 Average:5730.49 Median:4650.27 stddev:3363.89 (72.3%) stddev-sans-first:3356.90 22:35:02 INFO - PROCESS | 20678 | Values: 1307.1 1439.5 1504.0 1602.4 1766.9 7582.4 8613.7 8934.4 8962.1 9202.5 8524.6 8594.6 8596.4 8601.8 8601.9 2313.7 2319.1 2333.6 2333.9 2347.6 2306.4 2347.7 2371.3 2372.9 2374.4 2307.3 2314.8 2320.2 2368.4 2372.5 2306.1 2327.7 2337.5 2340.3 2345.6 2280.6 2283.7 2284.1 2286.6 2291.9 8686.3 8901.7 9068.9 9138.4 9139.2 5095.5 5293.0 5294.6 5298.3 5305.3 3209.4 3421.7 3435.9 3445.9 3478.3 1063.9 1100.1 1100.9 1113.6 1118.5 12617.6 12721.6 12775.4 12864.4 12893.8 4270.1 4496.2 4507.7 4594.6 4638.1 9520.1 9562.2 9578.6 9622.1 9637.1 4958.7 4960.3 4961.3 4966.5 4979.8 4116.5 4227.4 4435.8 4516.8 4595.6 3699.2 3729.8 3816.8 3825.7 3878.2 1190.8 1203.2 1205.0 1210.8 1221.9 805.3 871.0 897.7 907.5 908.6 5600.5 5610.9 5941.3 5978.7 6181.1 948.3 953.0 954.1 957.5 999.1 3739.5 3940.9 3941.0 4018.2 4039.2 11135.7 11603.0 11660.8 11662.9 11691.7 11382.1 11508.7 11518.7 11528.9 11530.5 8064.7 8116.7 8137.1 8138.9 8158.8 5506.6 5510.4 5661.2 5684.3 5811.3 10093.3 10107.4 10113.9 10206.1 10245.3 10071.9 10223.4 10249.4 10284.9 10409.4 6555.9 6733.9 6740.1 7328.0 7439.0 7845.4 8368.0 8632.5 8687.1 8883.3 7517.0 7570.8 7665.2 7722.1 7790.6 7281.2 7324.8 7511.4 7534.1 7606.8 6838.6 7045.8 7049.2 7060.9 7063.9 4568.2 4666.1 4754.6 4763.0 4849.3 4662.4 4784.3 4827.1 4850.7 5024.6 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#4] prototype.html Cycles:180 Average:4375.36 Median:4850.78 stddev:1232.25 (25.4%) stddev-sans-first:1197.68 22:35:02 INFO - PROCESS | 20678 | Values: 328.6 332.4 339.2 340.1 344.9 5101.9 5193.6 5195.2 5318.2 5324.7 5064.2 5210.3 5226.3 5248.7 5253.2 4157.0 4157.7 4166.5 4166.6 4182.1 4073.8 4109.8 4188.0 4190.2 4206.9 4054.7 4070.5 4083.5 4085.6 4086.8 4076.4 4084.6 4094.4 4101.1 4120.2 3619.2 3631.4 3633.9 3650.8 3652.5 5008.5 5059.3 5070.6 5098.7 5108.3 4599.9 4618.8 4629.2 4630.5 4653.1 5420.9 5433.3 5435.6 5443.3 5479.1 3744.3 3893.6 3930.6 3938.4 3944.6 5608.7 5609.2 5609.8 5651.6 5683.9 3980.2 4036.0 4036.6 4041.7 4056.6 3985.8 4044.8 4065.3 4088.7 4097.6 4615.2 4619.5 4620.3 4634.4 4639.0 5432.2 5438.0 5443.9 5488.6 5510.5 1308.4 1329.9 1357.5 1374.7 1400.7 944.0 954.3 962.3 965.8 972.5 5213.2 5216.6 5226.5 5251.6 5267.2 5541.6 5555.7 5558.3 5566.9 5584.8 5019.3 5205.8 5208.4 5241.9 5250.9 2634.3 2647.4 2650.7 2653.6 2665.7 5107.6 5110.5 5129.6 5140.3 5154.0 4696.3 5041.7 5054.7 5070.7 5091.7 5058.6 5059.9 5087.0 5089.8 5107.0 4951.6 4972.8 4996.2 5018.9 5044.2 4582.5 4865.9 4868.7 4869.0 4902.4 5033.4 5041.7 5047.4 5075.3 5080.0 3687.5 3714.7 3731.2 3769.7 3770.5 4763.5 4877.8 4921.7 5063.9 5077.7 4726.7 4741.2 4835.6 4860.9 4874.2 4779.9 4784.2 4821.0 4826.7 4836.8 4658.6 4734.0 4792.6 4793.3 4795.8 4845.3 4849.1 4849.2 4864.4 4913.7 4826.9 4830.9 4852.4 4877.5 4891.7 22:35:02 INFO - PROCESS | 20678 | 22:35:02 INFO - PROCESS | 20678 | [#5] yui.html Cycles:180 Average:20607.53 Median:54976.73 stddev:31961.71 (58.1%) stddev-sans-first:32016.15 22:35:02 INFO - PROCESS | 20678 | Values: 623.7 681.0 688.2 692.4 707.6 13717.2 14498.2 14555.1 14617.2 14623.8 14264.3 14290.6 14311.6 14314.9 14387.6 646.4 658.0 770.3 780.1 784.0 623.1 639.1 639.3 639.5 639.9 841.9 843.0 843.9 844.1 844.3 679.4 695.9 798.8 838.1 839.1 609.9 611.9 612.8 613.3 613.5 14302.1 14327.1 14341.9 14362.6 14370.4 12141.8 12195.5 12224.5 12573.7 12651.7 10298.2 10441.2 10486.4 10676.5 11554.3 4260.2 4344.5 4351.5 4510.5 4525.2 78087.0 79997.2 80268.2 80740.0 81567.6 10009.4 10175.7 10184.4 10221.6 10268.2 17771.7 17773.8 17780.5 17782.9 17790.0 7909.0 7950.7 8003.3 8067.1 8095.9 4247.4 4387.2 4419.1 4518.8 4527.3 3396.2 3511.8 3517.2 3517.6 3547.1 575.5 580.7 583.9 585.2 607.3 495.7 527.6 590.9 603.9 642.2 8427.6 8480.8 8518.8 8699.2 8850.2 632.9 635.0 647.7 666.6 668.2 3843.8 3922.1 3922.5 4007.0 4229.5 124081.0 124556.0 129952.0 131666.4 132691.0 103026.0 104336.0 104439.0 105193.0 105597.5 58424.1 59889.7 64606.4 91578.0 94336.5 7204.6 7211.5 7364.7 7473.7 7537.2 54715.7 55237.7 55856.0 56320.2 57172.7 76937.0 81780.2 82956.2 83990.0 84491.0 9363.6 9419.0 9701.6 10424.5 10456.2 12360.9 12375.9 12430.8 12463.2 13238.3 10953.2 11160.2 11183.8 11368.4 11680.6 11278.5 11630.1 11679.2 11696.9 12005.8 11479.3 11563.6 11687.5 11832.6 12116.6 9434.8 9594.0 9746.4 9777.2 9777.9 9396.2 9739.9 9884.2 9896.4 10096.2 22:35:02 INFO - PROCESS | 20678 | -------- Summary: end -------- 22:35:02 INFO - PROCESS | 20678 | 22:35:03 INFO - TEST-INFO | 20678: exit 0 22:35:03 INFO - TEST-OK | dromaeo_css | took 1116586ms 22:35:03 INFO - TEST-START | kraken 22:35:03 INFO - operating with platform_type : linux_ 22:35:03 INFO - Initialising browser for kraken test... 22:35:03 INFO - TEST-INFO | started process 20989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGl1oUN/profile http://localhost:58357/getInfo.html) 22:35:07 INFO - PROCESS | 20989 | __metrics Screen width/height:1600/1200 22:35:07 INFO - PROCESS | 20989 | colorDepth:24 22:35:07 INFO - PROCESS | 20989 | Browser inner width/height: 1024/697 22:35:07 INFO - PROCESS | 20989 | __metrics 22:35:08 INFO - PROCESS | 20989 | 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" 22:35:08 INFO - PROCESS | 20989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:35:08 INFO - PROCESS | 20989 | console.error: PushService: 22:35:08 INFO - PROCESS | 20989 | stateChangeProcessEnqueue: Error transitioning state 22:35:08 INFO - PROCESS | 20989 | UnknownError 22:35:09 INFO - TEST-INFO | 20989: exit 0 22:35:09 INFO - Browser initialized. 22:35:09 INFO - Running cycle 1/1 for kraken test... 22:35:09 INFO - TEST-INFO | started process 21071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGl1oUN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:35:21 INFO - PROCESS | 21071 | RSS: Main: 174354432 22:35:21 INFO - PROCESS | 21071 | 22:35:24 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58357/tests/kraken/driver.html?testName=audio-beat-detection) 22:35:25 INFO - PROCESS | 21071 | RSS: Main: 232202240 22:35:25 INFO - PROCESS | 21071 | 22:35:27 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58357/tests/kraken/driver.html?testName=audio-dft) 22:35:28 INFO - PROCESS | 21071 | RSS: Main: 216526848 22:35:28 INFO - PROCESS | 21071 | 22:35:31 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58357/tests/kraken/driver.html?testName=audio-fft) 22:35:32 INFO - PROCESS | 21071 | RSS: Main: 298389504 22:35:32 INFO - PROCESS | 21071 | 22:35:34 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58357/tests/kraken/driver.html?testName=audio-oscillator) 22:35:34 INFO - PROCESS | 21071 | RSS: Main: 209268736 22:35:34 INFO - PROCESS | 21071 | 22:35:36 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58357/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:35:37 INFO - PROCESS | 21071 | RSS: Main: 213024768 22:35:37 INFO - PROCESS | 21071 | 22:35:40 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58357/tests/kraken/driver.html?testName=imaging-darkroom) 22:35:41 INFO - PROCESS | 21071 | RSS: Main: 249212928 22:35:41 INFO - PROCESS | 21071 | 22:35:44 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58357/tests/kraken/driver.html?testName=imaging-desaturate) 22:35:44 INFO - PROCESS | 21071 | RSS: Main: 248774656 22:35:44 INFO - PROCESS | 21071 | 22:35:47 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58357/tests/kraken/driver.html?testName=json-parse-financial) 22:35:48 INFO - PROCESS | 21071 | RSS: Main: 248221696 22:35:48 INFO - PROCESS | 21071 | 22:35:49 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58357/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:35:50 INFO - PROCESS | 21071 | RSS: Main: 210644992 22:35:50 INFO - PROCESS | 21071 | 22:35:52 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:35:52 INFO - PROCESS | 21071 | RSS: Main: 217710592 22:35:52 INFO - PROCESS | 21071 | 22:35:54 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:35:55 INFO - PROCESS | 21071 | RSS: Main: 222437376 22:35:55 INFO - PROCESS | 21071 | 22:35:58 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:35:58 INFO - PROCESS | 21071 | RSS: Main: 223342592 22:35:58 INFO - PROCESS | 21071 | 22:36:01 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:36:02 INFO - PROCESS | 21071 | RSS: Main: 221663232 22:36:02 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | Cycle 1(1): loaded http://localhost:58357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58357/tests/kraken/driver.html?testName=ai-astar) 22:36:03 INFO - PROCESS | 21071 | RSS: Main: 273616896 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | __start_tp_report 22:36:03 INFO - PROCESS | 21071 | _x_x_mozilla_page_load 22:36:03 INFO - PROCESS | 21071 | _x_x_mozilla_page_load_details 22:36:03 INFO - PROCESS | 21071 | |i|pagename|runs| 22:36:03 INFO - PROCESS | 21071 | |0;ai-astar;110;111;110;111;111;110;110;110;111;111 22:36:03 INFO - PROCESS | 21071 | |1;audio-beat-detection;145;119;126;160;126;123;118;120;149;119 22:36:03 INFO - PROCESS | 21071 | |2;audio-dft;161;160;160;159;161;161;162;161;162;160 22:36:03 INFO - PROCESS | 21071 | |3;audio-fft;75;75;75;111;76;77;76;76;104;74 22:36:03 INFO - PROCESS | 21071 | |4;audio-oscillator;83;82;108;82;82;83;109;83;84;112 22:36:03 INFO - PROCESS | 21071 | |5;imaging-gaussian-blur;117;117;118;117;117;118;117;117;117;118 22:36:03 INFO - PROCESS | 21071 | |6;imaging-darkroom;145;146;147;145;145;146;145;145;145;145 22:36:03 INFO - PROCESS | 21071 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 22:36:03 INFO - PROCESS | 21071 | |8;json-parse-financial;52;53;51;50;52;52;53;53;52;52 22:36:03 INFO - PROCESS | 21071 | |9;json-stringify-tinderbox;60;60;60;60;60;61;61;60;64;60 22:36:03 INFO - PROCESS | 21071 | |10;stanford-crypto-aes;80;81;79;79;79;87;77;79;78;81 22:36:03 INFO - PROCESS | 21071 | |11;stanford-crypto-ccm;134;138;124;130;123;125;126;138;169;124 22:36:03 INFO - PROCESS | 21071 | |12;stanford-crypto-pbkdf2;166;166;161;162;161;162;163;163;161;165 22:36:03 INFO - PROCESS | 21071 | |13;stanford-crypto-sha256-iterative;67;68;64;63;65;63;62;66;69;62 22:36:03 INFO - PROCESS | 21071 | __end_tp_report 22:36:03 INFO - PROCESS | 21071 | __start_cc_report 22:36:03 INFO - PROCESS | 21071 | _x_x_mozilla_cycle_collect,1640 22:36:03 INFO - PROCESS | 21071 | __end_cc_report 22:36:03 INFO - PROCESS | 21071 | __startTimestamp1475472963965__endTimestamp 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | ------- Summary: start ------- 22:36:03 INFO - PROCESS | 21071 | Number of tests: 14 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:36:03 INFO - PROCESS | 21071 | Values: 110.0 111.0 110.0 111.0 111.0 110.0 110.0 110.0 111.0 111.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:126.00 stddev:15.09 (12.0%) stddev-sans-first:15.07 22:36:03 INFO - PROCESS | 21071 | Values: 145.0 119.0 126.0 160.0 126.0 123.0 118.0 120.0 149.0 119.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 22:36:03 INFO - PROCESS | 21071 | Values: 161.0 160.0 160.0 159.0 161.0 161.0 162.0 161.0 162.0 160.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.50 stddev:13.62 (18.0%) stddev-sans-first:14.21 22:36:03 INFO - PROCESS | 21071 | Values: 75.0 75.0 75.0 111.0 76.0 77.0 76.0 76.0 104.0 74.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:82.50 stddev:13.07 (15.8%) stddev-sans-first:13.56 22:36:03 INFO - PROCESS | 21071 | Values: 83.0 82.0 108.0 82.0 82.0 83.0 109.0 83.0 84.0 112.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:36:03 INFO - PROCESS | 21071 | Values: 117.0 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 118.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 22:36:03 INFO - PROCESS | 21071 | Values: 145.0 146.0 147.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:36:03 INFO - PROCESS | 21071 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:0.94 (1.8%) stddev-sans-first:1.00 22:36:03 INFO - PROCESS | 21071 | Values: 52.0 53.0 51.0 50.0 52.0 52.0 53.0 53.0 52.0 52.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.00 stddev:1.26 (2.1%) stddev-sans-first:1.32 22:36:03 INFO - PROCESS | 21071 | Values: 60.0 60.0 60.0 60.0 60.0 61.0 61.0 60.0 64.0 60.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:2.75 (3.5%) stddev-sans-first:2.92 22:36:03 INFO - PROCESS | 21071 | Values: 80.0 81.0 79.0 79.0 79.0 87.0 77.0 79.0 78.0 81.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:132.00 stddev:13.87 (10.5%) stddev-sans-first:14.71 22:36:03 INFO - PROCESS | 21071 | Values: 134.0 138.0 124.0 130.0 123.0 125.0 126.0 138.0 169.0 124.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.00 stddev:2.00 (1.2%) stddev-sans-first:1.80 22:36:03 INFO - PROCESS | 21071 | Values: 166.0 166.0 161.0 162.0 161.0 162.0 163.0 163.0 161.0 165.0 22:36:03 INFO - PROCESS | 21071 | 22:36:03 INFO - PROCESS | 21071 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.50 stddev:2.51 (3.8%) stddev-sans-first:2.55 22:36:03 INFO - PROCESS | 21071 | Values: 67.0 68.0 64.0 63.0 65.0 63.0 62.0 66.0 69.0 62.0 22:36:03 INFO - PROCESS | 21071 | -------- Summary: end -------- 22:36:03 INFO - PROCESS | 21071 | 22:36:05 INFO - TEST-INFO | 21071: exit 0 22:36:05 INFO - TEST-OK | kraken | took 61735ms 22:36:05 INFO - SUITE-END | took 1178s 22:36:05 INFO - Completed test suite (00:19:38) 22:36:05 INFO - javascript benchmark 22:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.2917628945343, 3096.071178646407, 3108.4715598348284, 3127.4683303838347, 3151.9684803151963, 5275.446078161795, 5350.5452036576935, 5399.568034557231, 5515.283013208283, 5527.115661494162, 5386.8922621547545, 5420.756065977031, 5488.807891723791, 5520.6687598744, 5576.219329293902, 4886.951130488703, 4929.728864912428, 4936.358273424467, 4959.950400496003, 5095.159298715726, 5098.311727916739, 5106.055379754739, 5110.1312776827845, 5114.641975061747, 5116.2069879168785, 4872.9756351218375, 4917.311576379309, 4918.803247870081, 5107.080725469414, 5132.28148059272, 4887.437944636376, 4910.607151427877, 4938.9753051234875, 4997.900041999176, 5017.297578339036, 4953.2074868020945, 5000.374953130859, 5004.5495905368325, 5020.246962955549, 5025.12060389434, 5421.83734487966, 5446.945530544684, 5450.454954504517, 5453.291072160594, 5456.454354564551, 5622.100463925753, 5850.765969361263, 5852.56105792063, 5855.76576936922, 5918.053111502139, 5716.542676585863, 5752.9424705753245, 5771.451712087387, 5775.1337299405, 5877.882442351171, 5363.892722145574, 5404.405515393303, 5415.83752487426, 5420.891582168374, 5423.783048678088, 5882.941170588325, 5918.201042859245, 5977.820665380045, 5981.252343457068, 5999.160117583542, 5599.7480113394995, 5614.214010038598, 5646.802361917313, 5647.0, 5655.123455864347, 5780.855478612984, 5782.797602083991, 5784.132380142928, 5837.270341207349, 5851.590388672837, 5824.650520968756, 5829.5044921181325, 5837.211976383134, 5838.474537291664, 5840.737166827503, 5686.630369026, 5735.254416925856, 5767.538596912237, 5821.79623713168, 5828.271466066742, 4509.616682581952, 4511.774411279423, 4558.247889098262, 4573.131105090022, 4573.702709323949, 3967.1272320089533, 3993.4608827807874, 4010.7794071325775, 4016.196760647882, 4051.554329023805, 5345.1447768356875, 5360.91958620621, 5394.056040192951, 5447.618666693294, 5512.6968016758665, 5840.678762668094, 5896.292444906553, 5899.911501327483, 5968.104784282393, 5974.312954010257, 5477.67133971963, 5492.340919089655, 5496.862578435571, 5503.807366742145, 5515.503604675599, 4944.653874228841, 4987.925181122286, 5089.083964886282, 5102.642815002914, 5157.974210128926, 5932.614380065368, 5977.432143946318, 5985.431384018511, 5990.430909063632, 5992.041273396236, 5764.913526297193, 5799.76800927975, 5808.651480911159, 5817.331006934088, 5826.300843898759, 5987.0, 5988.6706231157705, 5993.130996005642, 5994.580379373402, 6001.129836173817, 5742.023855944583, 5784.884302313846, 5801.709914504174, 5886.852828679317, 5896.6756828374855, 5933.584649074523, 5936.554758393224, 5936.762529498772, 5937.2578427696535, 5990.880182396415, 5772.855678608068, 5786.479216870502, 5786.884262314814, 5789.1895134680335, 5792.333881603669, 5572.60991730575, 5594.41258667834, 5620.718964051863, 5641.492265696107, 5755.942440575708, 5667.0, 5727.513161381329, 5733.4266573344, 5736.4550367715, 5738.282714660668, 5702.401247868914, 5716.142578613241, 5716.885662286648, 5721.284839395075, 5729.255196824386, 5452.154915988028, 5584.916226256691, 5602.075657516627, 5605.523530499953, 5607.719614019365], "value": 5379.274639924826, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.7394476706413, 558.4331903118334, 563.7744902039184, 627.7614506487537, 639.5011890725236, 31647.841760791194, 42475.0, 42516.0, 42521.0, 47797.28304075433, 44630.33054504185, 44885.32672009923, 44984.77507612466, 45022.774886125604, 45038.774806125926, 8818.603162857655, 8976.955115224448, 8978.551072446384, 8979.14698103679, 8979.820403591924, 8761.08008659088, 8805.471671699719, 8854.601542930552, 8860.955695221515, 8863.512506812123, 8864.379493435463, 8886.911130888706, 8934.553272336358, 8939.150780675825, 8939.597718102668, 8733.257673097762, 8859.867101993475, 8869.866951995726, 8873.245774109208, 8880.200781929658, 8834.469931804113, 8880.822383552357, 8897.288216942628, 8905.243054340388, 8905.910940890637, 41238.1752364954, 41247.175056499, 41575.1684966302, 41645.583544164474, 41820.790896045626, 2578.1878708206855, 2602.1803132013356, 2672.1449136276206, 2866.6130072440164, 3108.844557772125, 31624.841875790706, 31732.841335793404, 32253.838730806197, 33823.830880845686, 33829.83085084559, 3940.2513522430645, 3959.208158368309, 3962.366021436585, 3975.0, 4118.773467459288, 329559.0, 333389.0, 333682.99999999756, 333802.0, 334952.0, 58556.7072164645, 58613.0, 58912.70543647254, 58987.41012589905, 59047.40952590505, 53863.730681346344, 61302.693486532284, 61311.0, 61491.0, 61515.0, 13844.238566878917, 15578.688426231412, 15889.728821694236, 15962.12208328553, 15984.200789960689, 11646.708832279091, 11876.524939002344, 11881.702957426134, 12169.634910952685, 12171.0, 16971.30286971293, 19452.416427507196, 19463.0, 19551.315703950295, 19650.606987860163, 5372.785088596491, 6461.0, 6536.411722944958, 6709.228438729511, 6847.93152068473, 877.925376343018, 952.623713633111, 959.3811991265734, 989.0, 1051.9631812886512, 30919.934827777062, 32729.67270327314, 32972.1515970124, 33040.50439243413, 33296.0, 978.8678528398575, 1028.6142696488816, 1032.4940779018227, 1036.0468369100445, 1056.287006270764, 16522.42171523991, 17281.827181728022, 17330.133493325535, 17390.82609173892, 17577.824221757874, 518381.0, 520161.0, 520967.0, 522319.0, 525272.0, 57465.71267143638, 57854.13218801721, 57873.0, 58016.0, 58023.0, 1172.6377923560974, 1218.585680868509, 1229.274727910537, 1234.8147777833217, 1244.1788419642992, 30456.84771576128, 31367.84316078405, 31803.840980794947, 32209.51685724669, 32279.0, 50277.24584131168, 50554.99999999853, 50560.494395055575, 50639.0, 50671.74664126656, 428610.283382998, 431189.0, 431485.0, 431807.0, 432138.0, 33741.49387759136, 34494.48258276078, 39841.80079099586, 42735.1452970945, 43367.56632433635, 46534.30198547092, 47200.7639961798, 47390.52609473999, 47775.76112119556, 48169.0, 38980.80509597434, 39922.0, 40090.39864402095, 41075.58924410837, 41386.58613413827, 39559.0, 39740.40389394104, 40525.0, 40544.1891162181, 41890.58109418867, 36570.085747856516, 36616.81691591525, 37123.25753484969, 37131.25737485289, 37356.252874941805, 33333.83333083416, 35523.64476355203, 36332.63667363292, 36910.63089369072, 37099.44350834793, 27795.30511737142, 28220.85889570539, 28228.71771282261, 28256.717432825408, 28287.575686365133], "value": 19666.723579844394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.2994125544647, 390.53916378673154, 398.12214067980096, 402.88316388247404, 416.704140060557, 14206.502772402968, 17349.653006939854, 17691.82308176918, 19409.0, 19409.611807763853, 18087.72868406975, 18116.728249076274, 18245.817541824577, 18369.0, 18523.0, 5556.638818476807, 5585.0, 5727.570432217601, 5758.596898217126, 5761.251037365137, 4139.523954745197, 5705.372409035002, 5776.971115144418, 5786.710664466781, 5789.739461724212, 4161.888903291422, 6071.635701857903, 6335.461485773715, 6377.681115944208, 6412.647304398256, 6099.115628233882, 6292.87414251717, 6340.619562826199, 6400.199975003125, 6414.807555773335, 5586.217929489874, 5600.915986260209, 5602.187682785973, 5608.831735047955, 5609.4293597792275, 21906.823510590875, 22648.773512264997, 22708.54582908349, 22910.083596656114, 23182.76817231823, 2248.0, 2355.8704271265074, 2387.7612238776155, 2586.107792811477, 2941.558766185068, 13589.252591107484, 13817.0, 14377.640558986004, 14392.712145757027, 14448.422063117463, 1823.0, 1857.359211072178, 1872.2510995601733, 1879.9718004229946, 1888.688366419539, 81083.5945820273, 81955.59022204851, 82233.58883205605, 82926.0, 83453.58273208656, 9450.952745236229, 9727.173190278905, 9747.512624368754, 10298.897011029943, 10572.682819515427, 12996.285204313665, 14841.257937103317, 14918.776218356734, 14928.25358732059, 14943.551693449213, 7291.781246562611, 7573.962130189314, 7773.378129749606, 7935.484193527403, 7981.920180798234, 7135.82160445993, 7317.41460683144, 7541.170471248159, 7625.263620825755, 7626.733064342721, 6418.261899881573, 6421.404013752221, 6440.903386449207, 6549.803505894831, 6752.291009444036, 1878.5773201029933, 1901.8478521718507, 1902.144035184145, 1931.8357939575294, 1986.5728868293386, 748.8389996150854, 838.2120806442047, 852.1989330029876, 920.8848893888264, 979.9910184122663, 17500.299988000337, 17641.7353739694, 17821.376251831123, 18683.439496815117, 18991.0, 857.5883575883486, 882.1311008656468, 889.3552174673439, 897.699270744308, 948.4972964328916, 9760.658376956773, 10565.524551395205, 10604.681859544226, 10665.045616937196, 10712.371195425614, 457450.0, 463659.0, 466010.0, 467837.0, 472975.0, 80958.0, 81614.59192703999, 82384.58807705923, 83272.58363708142, 84415.0, 1007.1489591295343, 1055.261317078033, 1065.9093977011885, 1066.5253961986841, 1085.8751243607087, 16648.83351166473, 17132.743008854628, 17541.736873947157, 17564.82435175683, 17583.64832703313, 39557.40663890102, 40283.59716402798, 41150.79424602858, 42058.15883682248, 44172.51514606161, 55689.16466253091, 55690.72154639201, 56925.43074569367, 56940.43059569517, 58864.0, 8013.15861834499, 8015.198480151938, 8267.29727973129, 8403.663853445794, 8550.57247137653, 11316.434178290889, 12527.123101382816, 12620.747585048432, 12708.110432269652, 13437.0, 11255.0, 11413.60052398162, 11474.789706833706, 11872.168948173545, 11927.343996080299, 9911.851322230317, 10342.276040677081, 10769.784604308026, 11248.606298779503, 11621.12841536905, 6234.251889773256, 11535.885310965825, 11537.423128843691, 11636.53453861836, 11726.179167458196, 7265.963670181615, 7410.296021877913, 7571.848563028819, 7779.027621547307, 8542.572871356531, 9783.315167938175, 11079.279846809932, 11162.497687603915, 11325.773484530428, 11755.471003804678], "value": 9070.741150978143, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.1372893890023, 1439.4745917740029, 1503.9924800375998, 1602.3510478256303, 1766.9028203448804, 7582.393408527319, 8613.698520551781, 8934.374593778439, 8962.058983806704, 9202.539873006357, 8524.573771311441, 8594.570271486433, 8596.355273354508, 8601.784955376119, 8601.870971935426, 2313.7223533176, 2319.1071437496516, 2333.6266197408418, 2333.871637059961, 2347.6243800991847, 2306.423394151462, 2347.6595893595418, 2371.27676058802, 2372.8813559321966, 2374.3589230907646, 2307.3193407944614, 2314.7685231476885, 2320.2227253869974, 2368.3723813189517, 2372.50177462733, 2306.1351993002622, 2327.7323107842644, 2337.4857531343077, 2340.33300509354, 2345.6129738593113, 2280.635098384267, 2283.668868014145, 2284.097781376364, 2286.588414085464, 2291.8968646410954, 8686.261667758248, 8901.688440904602, 9068.909310906873, 9138.360314777969, 9139.177474027369, 5095.541401273866, 5293.0, 5294.602904782157, 5298.258243845865, 5305.283786688825, 3209.3741720364537, 3421.7091547218515, 3435.9312813743836, 3445.879394221215, 3478.3217272631846, 1063.8936106389376, 1100.147385776022, 1100.9394483303415, 1113.565709373345, 1118.4799068433217, 12617.558385456465, 12721.618351449379, 12775.361231938366, 12864.356782160854, 12893.806592901208, 4270.145970805851, 4496.1906856765445, 4507.684462087623, 4594.609458196091, 4638.0955713636195, 9520.143187113194, 9562.187214086742, 9578.56896439662, 9622.13400793932, 9637.084476974676, 4958.677685950402, 4960.255961605788, 4961.2806143109765, 4966.503349665077, 4979.800807967713, 4116.464859568297, 4227.42929704486, 4435.822567097344, 4516.774161291923, 4595.563421474954, 3699.2231631357176, 3729.776213427203, 3816.8473261069803, 3825.7236944018264, 3878.185581028016, 1190.8213767934878, 1203.181836351272, 1204.9939750301191, 1210.84864391951, 1221.8533775946767, 805.275252272959, 871.0, 897.7396554998977, 907.5462268865567, 908.5684299957468, 5600.467955544217, 5610.87782443513, 5941.257342832146, 5978.730957106854, 6181.072839074175, 948.3266880514774, 952.9666461673808, 954.1031430455349, 957.5164541906432, 999.0708640963828, 3739.5325584301963, 3940.8817735467983, 3941.0, 4018.1561872007205, 4039.171969746195, 11135.721606959729, 11603.013743831869, 11660.76678466426, 11662.94168529152, 11691.707707307385, 11382.089432845352, 11508.654740357802, 11518.654440366803, 11528.942355288338, 11530.538778448936, 8064.717734879251, 8116.715914943066, 8137.145599712064, 8138.877916831252, 8158.796030099176, 5506.559475242051, 5510.3938566758425, 5661.150827375926, 5684.317881854204, 5811.331696854747, 10093.293469457361, 10107.444090575089, 10113.949430252802, 10206.081452669296, 10245.282830201815, 10071.949640251752, 10223.437710925971, 10249.436281004617, 10284.897151028394, 10409.42748148859, 6555.901661474986, 6733.93266067333, 6740.090087838275, 7328.0, 7439.0, 7845.411594130577, 8368.0, 8632.525211113449, 8687.131286871263, 8883.289336852908, 7516.962415187889, 7570.772876813704, 7665.156832748282, 7722.111957125003, 7790.649420776192, 7281.162666293444, 7324.816879578053, 7511.436642251743, 7534.095908491016, 7606.8098297543, 6838.555493892881, 7045.753398631024, 7049.224585295511, 7060.858782824212, 7063.929360706327, 4568.200564901195, 4666.113438446684, 4754.572088511912, 4762.9523704762505, 4849.2968519565175, 4662.440507139029, 4784.258439941815, 4827.106985207748, 4850.733209673502, 5024.623153263593], "value": 4552.491924380352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.5941861800677, 332.38176990797115, 339.23164033464195, 340.1055224758884, 344.8706734974385, 5101.872453188663, 5193.558547523456, 5195.168772996321, 5318.175682769167, 5324.680519168853, 5064.189729643258, 5210.270562121296, 5226.268322434862, 5248.658837175581, 5253.159494480884, 4156.958430415687, 4157.729747566398, 4166.500019997605, 4166.64583510402, 4182.142660754538, 4073.8370465181356, 4109.815058322382, 4187.989764912843, 4190.203861266365, 4206.917575799968, 4054.6553542948886, 4070.4911886014247, 4083.4895638045246, 4085.5710150434224, 4086.7752273624938, 4076.4496792932873, 4084.6323830855217, 4094.3653733671326, 4101.138760860223, 4120.196561670476, 3619.2218672985173, 3631.4371272452813, 3633.8728144514816, 3650.8174591270595, 3652.4521321801676, 5008.499150084999, 5059.342285502861, 5070.594352451795, 5098.719570423626, 5108.2593024011685, 4599.862004139881, 4618.838340658061, 4629.213033784265, 4630.490646028934, 4653.115907977473, 5420.945790542083, 5433.293671822637, 5435.565154787608, 5443.319585051869, 5479.068558345051, 3744.326021316162, 3893.552241492236, 3930.567637559866, 3938.409238614202, 3944.585818489075, 5608.738033942371, 5609.214709940612, 5609.80365687199, 5651.5761317900915, 5683.914741278884, 3980.2238563480423, 4036.0425193780993, 4036.59634036594, 4041.6766658667234, 4056.59434056592, 3985.780782056957, 4044.838206471708, 4065.2682517146604, 4088.7342322748927, 4097.590240975878, 4615.16926953145, 4619.538046195354, 4620.260758278727, 4634.37435946143, 4639.0, 5432.239486471897, 5438.0, 5443.863903402367, 5488.588355873246, 5510.504054635102, 1308.358904136985, 1329.8936085113169, 1357.545222350504, 1374.6975665353607, 1400.7198560287984, 943.9575219115156, 954.3081266082035, 962.259060523407, 965.7730433348157, 972.5331840716495, 5213.165893457029, 5216.556592689588, 5226.529612334908, 5251.579873610102, 5267.183586544092, 5541.612087153861, 5555.666660000413, 5558.277423934863, 5566.888662226814, 5584.804531841366, 5019.322391477177, 5205.791768329224, 5208.374995000624, 5241.9213711794355, 5250.947490525123, 2634.3150780796745, 2647.430802377498, 2650.748178923038, 2653.6152257922495, 2665.7067722550505, 5107.646473684476, 5110.4634013428795, 5129.589632829365, 5140.331756871583, 5153.974230128901, 4696.299627714078, 5041.672291301127, 5054.721990290569, 5070.721110338893, 5091.7016160879475, 5058.620603454756, 5059.9121188944555, 5087.0, 5089.829339251919, 5106.974465127651, 4951.628627852998, 4972.801087956441, 4996.225585034325, 5018.874528136826, 5044.192929131468, 4582.450105987169, 4865.9270110947655, 4868.707877527218, 4869.003708479507, 4902.436219834622, 5033.370828646419, 5041.739565108723, 5047.394312682355, 5075.274406701591, 5079.984003199301, 3687.4837522747375, 3714.684251838624, 3731.197792474631, 3769.679577235856, 3770.490983717163, 4763.499832517536, 4877.80488780459, 4921.704697718148, 5063.873403164803, 5077.695338279715, 4726.740029298284, 4741.217699079752, 4835.564799168092, 4860.854174374775, 4874.246829940997, 4779.928301075417, 4784.229834961086, 4821.0, 4826.6621336506105, 4836.758162091811, 4658.627309815274, 4734.0, 4792.5926296263815, 4793.257045158006, 4795.808167673254, 4845.3216549682365, 4849.127157111613, 4849.199882019428, 4864.36763220779, 4913.70517768935, 4826.927596086131, 4830.903381932271, 4852.369192005017, 4877.487863774394, 4891.6575839692305], "value": 3992.139981334683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.7037407231566, 680.9829754256144, 688.1741909708348, 692.407991167552, 707.6002058836758, 13717.176969381831, 14498.202598857059, 14555.126692398437, 14617.196054217027, 14623.78064329036, 14264.286785660728, 14290.571282861505, 14311.642208944782, 14314.928425357859, 14387.568372948803, 646.3891622416794, 657.9834156228615, 770.2874840772291, 780.1145699630931, 783.9608019598998, 623.1431781300712, 639.0765344077786, 639.3350915048344, 639.4820195641522, 639.8624295776407, 841.8653015517518, 843.0431460292551, 843.9156084391542, 844.0588743550958, 844.252836239926, 679.4089142446054, 695.8712638161957, 798.7523867601062, 838.1450920061534, 839.0770152831897, 609.9420555047263, 611.8562137897591, 612.7671484835779, 613.3406587918006, 613.5183880653666, 14302.070365426214, 14327.068740531831, 14341.92829035848, 14362.569122926328, 14370.353334099991, 12141.757164856654, 12195.45120469572, 12224.511019559208, 12573.685657858536, 12651.683707907285, 10298.227632927561, 10441.164706823436, 10486.37081775096, 10676.51955661997, 11554.306741595532, 4260.190563792869, 4344.543822898613, 4351.49957754859, 4510.458744950594, 4525.185466616008, 78087.0, 79997.20002799956, 80268.19731802725, 80740.0, 81567.59216203941, 10009.399436033862, 10175.694729157989, 10184.388936663823, 10221.591136354611, 10268.178545716324, 17771.733423998652, 17773.822261777215, 17780.46658600244, 17782.91108544449, 17790.0, 7909.0, 7950.721724739722, 8003.279704826594, 8067.0722866871065, 8095.878561821577, 4247.405363249148, 4387.2103021455805, 4419.1382680377665, 4518.796654150571, 4527.298268768281, 3396.218869659943, 3511.841967111486, 3517.2086280586664, 3517.613062563116, 3547.113221694576, 575.4906907386909, 580.7183515994739, 583.9182514447981, 585.2362666719886, 607.3440684061203, 495.67780942387736, 527.6095689189972, 590.8877313310458, 603.9275286965592, 642.2036674523557, 8427.620757065946, 8480.787980300542, 8518.829623407497, 8699.217070463563, 8850.208140840323, 632.8544434779934, 634.964798704228, 647.7311915555086, 666.5534092158266, 668.214847554122, 3843.802043200539, 3922.1371298314325, 3922.4704664870446, 4006.959930400717, 4229.471316085489, 124081.0, 124556.0, 129952.0, 131666.41667916565, 132691.0, 103026.0, 104336.0, 104439.0, 105193.0, 105597.47201263944, 58424.12363814546, 59889.700551496964, 64606.35393646004, 91578.0, 94336.52831735935, 7204.56772593646, 7211.495195336276, 7364.66858991336, 7473.663685134074, 7537.2462753724185, 54715.72642136923, 55237.7238113823, 55856.0, 56320.15519767125, 57172.71413642905, 76937.0, 81780.18219817964, 82956.23237787874, 83990.0, 84491.0, 9363.578638961128, 9418.95290523543, 9701.563429645825, 10424.478776061318, 10456.163506919276, 12360.938195308965, 12375.938120309702, 12430.751384972431, 12463.189892656948, 13238.338083096, 10953.233273670767, 11160.218784684994, 11183.832242516532, 11368.374739389414, 11680.591179308683, 11278.548858045586, 11630.127740419672, 11679.182457227913, 11696.88303116958, 12005.75988480208, 11479.25384849982, 11563.595274165364, 11687.532498700297, 11832.585859494877, 12116.575919842764, 9434.820647419072, 9594.0, 9746.415215087118, 9777.217822574035, 9777.85333219988, 9396.248300135836, 9739.9026009739, 9884.209263258777, 9896.356736812084, 10096.16104354946], "value": 6921.118219876663, "unit": "score"}], "name": "dromaeo_css", "value": 7030.1206687754575, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 111.0, 111.0, 110.0, 110.0, 110.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 126.0, 160.0, 126.0, 123.0, 118.0, 120.0, 149.0, 119.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 161.0, 161.0, 162.0, 161.0, 162.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 111.0, 76.0, 77.0, 76.0, 76.0, 104.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 108.0, 82.0, 82.0, 83.0, 109.0, 83.0, 84.0, 112.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 51.0, 50.0, 52.0, 52.0, 53.0, 53.0, 52.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 64.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 79.0, 79.0, 87.0, 77.0, 79.0, 78.0, 81.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 124.0, 130.0, 123.0, 125.0, 126.0, 138.0, 169.0, 124.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 161.0, 162.0, 161.0, 162.0, 163.0, 163.0, 161.0, 165.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 64.0, 63.0, 65.0, 63.0, 62.0, 66.0, 69.0, 62.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1485.8, "alertThreshold": 2.0}]} 22:36:05 INFO - Return code: 0 22:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:36:05 INFO - # TBPL SUCCESS # 22:36:05 INFO - Running post-action listener: _resource_record_post_action 22:36:05 INFO - [mozharness: 2016-10-03 05:36:05.798435Z] Finished run-tests step (success) 22:36:05 INFO - Running post-run listener: _resource_record_post_run 22:36:05 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 403795968; Read time: 4; Write time: 388976 22:36:05 INFO - TinderboxPrint: CPU usage
13.1% 22:36:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 22:36:05 INFO - TinderboxPrint: I/O write bytes / time
403,795,968 / 388,976 22:36:05 INFO - TinderboxPrint: CPU idle
8,287.7 (86.8%) 22:36:05 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 22:36:05 INFO - TinderboxPrint: Swap in / out
0 / 0 22:36:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114626560; Read time: 0; Write time: 88196 22:36:05 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288034816; Read time: 0; Write time: 300756 22:36:06 INFO - Running post-run listener: _upload_blobber_files 22:36:06 INFO - Blob upload gear active. 22:36:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:36:06 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 22:36:06 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'] 22:36:06 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 22:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 22:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:07 INFO - (blobuploader) - INFO - Done attempting. 22:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:11 INFO - (blobuploader) - INFO - Done attempting. 22:36:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:36:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:11 INFO - (blobuploader) - INFO - Done attempting. 22:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:36:11 INFO - Return code: 0 22:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0859112c8818adce3ca188f71aaacc890328c63692a7b3a0fb9e8859791765f462ee57089ca9983849ba1dab889e7a860257a66ce9aa306a56ea95ce2cb3ebce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85a0d7fcae8040bf41c4036fb3d564fb5720fcf40831e8d95a8b3994c2aefe5acf6ceec75fe5c0eed1fca9d37f292eb2dce1d8b471c82ff8e4736b2a5134ba26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0f2dcb300635ec107918acb816df68ffe54b246540d0ee5123f749c2f9111ca33de7bc984f8e49e8e95b48ea2da759cf18076908a130ed86bcddaf482aa872d"} 22:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:36:11 INFO - Contents: 22:36:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0859112c8818adce3ca188f71aaacc890328c63692a7b3a0fb9e8859791765f462ee57089ca9983849ba1dab889e7a860257a66ce9aa306a56ea95ce2cb3ebce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85a0d7fcae8040bf41c4036fb3d564fb5720fcf40831e8d95a8b3994c2aefe5acf6ceec75fe5c0eed1fca9d37f292eb2dce1d8b471c82ff8e4736b2a5134ba26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0f2dcb300635ec107918acb816df68ffe54b246540d0ee5123f749c2f9111ca33de7bc984f8e49e8e95b48ea2da759cf18076908a130ed86bcddaf482aa872d"} 22:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:36:11 INFO - Copying logs to upload dir... 22:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.852531 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-02 22:36:11.558391) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-02 22:36:11.562276) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 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/0859112c8818adce3ca188f71aaacc890328c63692a7b3a0fb9e8859791765f462ee57089ca9983849ba1dab889e7a860257a66ce9aa306a56ea95ce2cb3ebce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85a0d7fcae8040bf41c4036fb3d564fb5720fcf40831e8d95a8b3994c2aefe5acf6ceec75fe5c0eed1fca9d37f292eb2dce1d8b471c82ff8e4736b2a5134ba26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0f2dcb300635ec107918acb816df68ffe54b246540d0ee5123f749c2f9111ca33de7bc984f8e49e8e95b48ea2da759cf18076908a130ed86bcddaf482aa872d"} build_url:https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/ZvNLuO2vRq6yxf-qliqGzg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0859112c8818adce3ca188f71aaacc890328c63692a7b3a0fb9e8859791765f462ee57089ca9983849ba1dab889e7a860257a66ce9aa306a56ea95ce2cb3ebce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/85a0d7fcae8040bf41c4036fb3d564fb5720fcf40831e8d95a8b3994c2aefe5acf6ceec75fe5c0eed1fca9d37f292eb2dce1d8b471c82ff8e4736b2a5134ba26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0f2dcb300635ec107918acb816df68ffe54b246540d0ee5123f749c2f9111ca33de7bc984f8e49e8e95b48ea2da759cf18076908a130ed86bcddaf482aa872d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-02 22:36:11.588188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:36:11.588557) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1475471688.277934-1117244097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004559 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 22:36:11.671458) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-02 22:36:11.671841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-02 22:36:11.672208) ========= ========= Total master_lag: 0.43 =========