builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1484182914.31 results: success (0) buildid: 20170111152017 builduid: 60cf64e359534d80b80a0ff7f846a2e4 revision: 11086d0d2e8e3451bc9223cc5470538d1c65b718 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.314285) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.314848) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.315421) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.340225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.340493) ========= 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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-11 17:01:54-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.1M=0s 2017-01-11 17:01:54 (40.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080019 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.435228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.435685) ========= 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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021975 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:54.485885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 17:01:54.486241) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 11086d0d2e8e3451bc9223cc5470538d1c65b718 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 11086d0d2e8e3451bc9223cc5470538d1c65b718 --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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-11 17:01:54,701 truncating revision to first 12 chars 2017-01-11 17:01:54,701 Setting DEBUG logging. 2017-01-11 17:01:54,701 attempt 1/10 2017-01-11 17:01:54,701 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/11086d0d2e8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 17:01:55,418 unpacking tar archive at: mozilla-aurora-11086d0d2e8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.030068 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 17:01:55.529867) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:55.530218) ========= script_repo_revision: 11086d0d2e8e3451bc9223cc5470538d1c65b718 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:55.530620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:55.530894) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 17:01:55.542939) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-01-11 17:01:55.543400) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:01:55 INFO - MultiFileLogger online at 20170111 17:01:55 in /builds/slave/test 17:01:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:01:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:55 INFO - {'append_to_log': False, 17:01:55 INFO - 'base_work_dir': '/builds/slave/test', 17:01:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:01:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:55 INFO - 'branch': 'Mozilla-Aurora', 17:01:55 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:55 INFO - 'config_files': ('talos/linux_config.py',), 17:01:55 INFO - 'default_actions': ('clobber', 17:01:55 INFO - 'read-buildbot-config', 17:01:55 INFO - 'download-and-extract', 17:01:55 INFO - 'populate-webroot', 17:01:55 INFO - 'create-virtualenv', 17:01:55 INFO - 'install', 17:01:55 INFO - 'run-tests'), 17:01:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:55 INFO - 'download_minidump_stackwalk': True, 17:01:55 INFO - 'download_symbols': 'ondemand', 17:01:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:55 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:55 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:55 INFO - 'installer_path': 'installer.exe', 17:01:55 INFO - 'log_level': 'info', 17:01:55 INFO - 'log_name': 'talos', 17:01:55 INFO - 'log_to_console': True, 17:01:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:01:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:01:55 INFO - 'opt_config_files': (), 17:01:55 INFO - 'pip_index': False, 17:01:55 INFO - 'sps_profile': False, 17:01:55 INFO - 'sps_profile_interval': 0, 17:01:55 INFO - 'suite': 'dromaeojs', 17:01:55 INFO - 'system_bits': '32', 17:01:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:55 INFO - 'title': 'talos-linux64-ix-025', 17:01:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:55 INFO - 'use_talos_json': True, 17:01:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:55 INFO - 'work_dir': 'build'} 17:01:55 INFO - [mozharness: 2017-01-12 01:01:55.853533Z] Running clobber step. 17:01:55 INFO - Running pre-action listener: _resource_record_pre_action 17:01:55 INFO - Running main action method: clobber 17:01:55 INFO - rmtree: /builds/slave/test/build 17:01:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:56 INFO - Running post-action listener: _resource_record_post_action 17:01:56 INFO - [mozharness: 2017-01-12 01:01:56.315931Z] Finished clobber step (success) 17:01:56 INFO - [mozharness: 2017-01-12 01:01:56.316031Z] Running read-buildbot-config step. 17:01:56 INFO - Running pre-action listener: _resource_record_pre_action 17:01:56 INFO - Running main action method: read_buildbot_config 17:01:56 INFO - Using buildbot properties: 17:01:56 INFO - { 17:01:56 INFO - "project": "", 17:01:56 INFO - "product": "firefox", 17:01:56 INFO - "script_repo_revision": "production", 17:01:56 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:01:56 INFO - "repository": "", 17:01:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:01:56 INFO - "buildid": "20170111152017", 17:01:56 INFO - "pgo_build": "True", 17:01:56 INFO - "basedir": "/builds/slave/test", 17:01:56 INFO - "buildnumber": 695, 17:01:56 INFO - "slavename": "talos-linux64-ix-025", 17:01:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:01:56 INFO - "platform": "ubuntu64_hw", 17:01:56 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:01:56 INFO - "branch": "mozilla-aurora", 17:01:56 INFO - "repo_path": "releases/mozilla-aurora", 17:01:56 INFO - "revision": "11086d0d2e8e3451bc9223cc5470538d1c65b718", 17:01:56 INFO - "stage_platform": "linux64-pgo", 17:01:56 INFO - "builduid": "60cf64e359534d80b80a0ff7f846a2e4", 17:01:56 INFO - "slavebuilddir": "test" 17:01:56 INFO - } 17:01:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 17:01:56 INFO - Running post-action listener: _resource_record_post_action 17:01:56 INFO - [mozharness: 2017-01-12 01:01:56.318438Z] Finished read-buildbot-config step (success) 17:01:56 INFO - [mozharness: 2017-01-12 01:01:56.318525Z] Running download-and-extract step. 17:01:56 INFO - Running pre-action listener: _resource_record_pre_action 17:01:56 INFO - Running main action method: download_and_extract 17:01:56 INFO - mkdir: /builds/slave/test/build/tests 17:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:56 INFO - trying https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 17:01:56 INFO - Downloading https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 17:01:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:01:56 INFO - Downloaded 1361 bytes. 17:01:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 17:01:56 INFO - Using the following test package requirements: 17:01:56 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 17:01:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:01:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:01:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'jsshell-linux-x86_64.zip'], 17:01:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:01:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 17:01:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:01:56 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:01:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:01:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 17:01:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:01:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:01:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 17:01:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:01:56 INFO - Fetch https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:01:57 INFO - Http code: 200 17:01:57 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 17:01:57 INFO - x-amz-cf-id: 5QIIqzP_Gb9LsolhbmNYZk9wHEX8ogyzRVTs8D2AvWc-CW3GVxTVHw== 17:01:57 INFO - x-amz-version-id: qn8CmSTqcIq0dF5njFfxNtAlDxw5jggL 17:01:58 INFO - Expected file size: 21944502 17:01:58 INFO - Obtained file size: 21944502 17:02:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 17:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:02:03 INFO - Http code: 200 17:02:03 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 17:02:03 INFO - x-amz-cf-id: alnRGhuPWuBxKADyUEyj4EP1wIyw769Mt9X7i7jrQ__0l6ihiXKjQw== 17:02:03 INFO - x-amz-version-id: qn8CmSTqcIq0dF5njFfxNtAlDxw5jggL 17:02:03 INFO - Expected file size: 21944502 17:02:03 INFO - Obtained file size: 21944502 17:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 17:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 17:02:08 INFO - Http code: 200 17:02:08 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 17:02:08 INFO - x-amz-cf-id: LZCaK9jHO4z6bIH2phfK4zLNcxP_4M47-gJL7emkRhNM-z7glY2rmA== 17:02:08 INFO - x-amz-version-id: rEld0N6LlB4jY5_KhO7UjBdPlr2SXGQ4 17:02:09 INFO - Expected file size: 14409038 17:02:09 INFO - Obtained file size: 14409038 17:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:10 INFO - trying https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 17:02:10 INFO - Downloading https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 17:02:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:02:14 INFO - Downloaded 64121984 bytes. 17:02:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 17:02:14 INFO - mkdir: /builds/slave/test/properties 17:02:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:02:14 INFO - Writing to file /builds/slave/test/properties/build_url 17:02:14 INFO - Contents: 17:02:14 INFO - build_url:https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 17:02:15 INFO - Running post-action listener: _resource_record_post_action 17:02:15 INFO - Running post-action listener: set_extra_try_arguments 17:02:15 INFO - [mozharness: 2017-01-12 01:02:15.085719Z] Finished download-and-extract step (success) 17:02:15 INFO - [mozharness: 2017-01-12 01:02:15.085944Z] Running populate-webroot step. 17:02:15 INFO - Running pre-action listener: _resource_record_pre_action 17:02:15 INFO - Running main action method: populate_webroot 17:02:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:02:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:02:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:02:15 INFO - '--tppagecycles', 17:02:15 INFO - '7'], 17:02:15 INFO - 'tests': ['tsvgm']}}, 17:02:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:02:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:02:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:02:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:02:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['damp', 'tps']}, 17:02:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'tests': ['damp', 'tps']}, 17:02:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['dromaeo_dom']}, 17:02:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:02:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['basic_compositor_video']}, 17:02:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:02:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'other-e10s': {'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['a11yr', 17:02:15 INFO - 'ts_paint', 17:02:15 INFO - 'tpaint', 17:02:15 INFO - 'sessionrestore', 17:02:15 INFO - 'sessionrestore_no_auto_restore', 17:02:15 INFO - 'tabpaint']}, 17:02:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['tsvgx', 17:02:15 INFO - 'tsvgr_opacity', 17:02:15 INFO - 'tart', 17:02:15 INFO - 'tscrollx', 17:02:15 INFO - 'cart']}, 17:02:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:02:15 INFO - 'tsvgr_opacity', 17:02:15 INFO - 'tart', 17:02:15 INFO - 'tscrollx', 17:02:15 INFO - 'cart']}, 17:02:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'talos_options': ['--disable-e10s'], 17:02:15 INFO - 'tests': ['tp5o']}, 17:02:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'tests': ['tp5o']}, 17:02:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'talos_options': ['--disable-e10s', 17:02:15 INFO - '--xperf_path', 17:02:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:15 INFO - 'tests': ['tp5n']}, 17:02:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:15 INFO - 'talos_options': ['--xperf_path', 17:02:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:15 INFO - 'tests': ['tp5n']}}, 17:02:15 INFO - 'talos.zip': {'path': '', 17:02:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:02:15 INFO - Running post-action listener: _resource_record_post_action 17:02:15 INFO - [mozharness: 2017-01-12 01:02:15.104726Z] Finished populate-webroot step (success) 17:02:15 INFO - [mozharness: 2017-01-12 01:02:15.104814Z] Running create-virtualenv step. 17:02:15 INFO - Running pre-action listener: _resource_record_pre_action 17:02:15 INFO - Running main action method: create_virtualenv 17:02:15 INFO - Creating virtualenv /builds/slave/test/build/venv 17:02:15 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 17:02:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:02:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:02:15 INFO - Using real prefix '/usr' 17:02:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:02:15 INFO - Installing distribute.............................................................................................................................................................................................done. 17:02:17 INFO - Installing pip.................done. 17:02:17 INFO - Return code: 0 17:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:02:17 INFO - Reading from file tmpfile_stdout 17:02:17 INFO - Output received: 17:02:17 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) 17:02:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:02:17 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')]} 17:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:17 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')]} 17:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:17 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:17 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 17:02:17 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 17:02:17 INFO - Using env: {'DISPLAY': ':0', 17:02:17 INFO - 'HOME': '/home/cltbld', 17:02:17 INFO - 'LANG': 'en_US.UTF-8', 17:02:17 INFO - 'LANGUAGE': 'en_US:en', 17:02:17 INFO - 'LOGNAME': 'cltbld', 17:02:17 INFO - 'MAIL': '/var/mail/cltbld', 17:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:17 INFO - 'MOZ_NO_REMOTE': '1', 17:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:17 INFO - 'NO_EM_RESTART': '1', 17:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:17 INFO - 'PWD': '/builds/slave/test', 17:02:17 INFO - 'SHELL': '/bin/bash', 17:02:17 INFO - 'SHLVL': '1', 17:02:17 INFO - 'TERM': 'linux', 17:02:17 INFO - 'TMOUT': '86400', 17:02:17 INFO - 'USER': 'cltbld', 17:02:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:17 INFO - '_': '/tools/buildbot/bin/python'} 17:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:18 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 17:02:18 INFO - Cleaning up... 17:02:18 INFO - Return code: 0 17:02:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:02:18 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')]} 17:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 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')]} 17:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:18 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 17:02:18 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 17:02:18 INFO - Using env: {'DISPLAY': ':0', 17:02:18 INFO - 'HOME': '/home/cltbld', 17:02:18 INFO - 'LANG': 'en_US.UTF-8', 17:02:18 INFO - 'LANGUAGE': 'en_US:en', 17:02:18 INFO - 'LOGNAME': 'cltbld', 17:02:18 INFO - 'MAIL': '/var/mail/cltbld', 17:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:18 INFO - 'MOZ_NO_REMOTE': '1', 17:02:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:18 INFO - 'NO_EM_RESTART': '1', 17:02:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:18 INFO - 'PWD': '/builds/slave/test', 17:02:18 INFO - 'SHELL': '/bin/bash', 17:02:18 INFO - 'SHLVL': '1', 17:02:18 INFO - 'TERM': 'linux', 17:02:18 INFO - 'TMOUT': '86400', 17:02:18 INFO - 'USER': 'cltbld', 17:02:18 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:18 INFO - '_': '/tools/buildbot/bin/python'} 17:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:18 INFO - Downloading/unpacking psutil>=3.1.1 17:02:18 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 17:02:18 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 17:02:18 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 17:02:18 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 17:02:18 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 17:02:18 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 17:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:21 INFO - Installing collected packages: psutil 17:02:21 INFO - Running setup.py install for psutil 17:02:21 INFO - building 'psutil._psutil_linux' extension 17:02:21 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 17:02:21 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 17:02:21 INFO - building 'psutil._psutil_posix' extension 17:02:21 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 17:02:21 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 17:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:21 INFO - Successfully installed psutil 17:02:21 INFO - Cleaning up... 17:02:21 INFO - Return code: 0 17:02:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:02:21 INFO - Using env: {'DISPLAY': ':0', 17:02:21 INFO - 'HOME': '/home/cltbld', 17:02:21 INFO - 'LANG': 'en_US.UTF-8', 17:02:21 INFO - 'LANGUAGE': 'en_US:en', 17:02:21 INFO - 'LOGNAME': 'cltbld', 17:02:21 INFO - 'MAIL': '/var/mail/cltbld', 17:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:21 INFO - 'MOZ_NO_REMOTE': '1', 17:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:21 INFO - 'NO_EM_RESTART': '1', 17:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:21 INFO - 'PWD': '/builds/slave/test', 17:02:21 INFO - 'SHELL': '/bin/bash', 17:02:21 INFO - 'SHLVL': '1', 17:02:21 INFO - 'TERM': 'linux', 17:02:21 INFO - 'TMOUT': '86400', 17:02:21 INFO - 'USER': 'cltbld', 17:02:21 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:21 INFO - '_': '/tools/buildbot/bin/python'} 17:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:02:21 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 17:02:21 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 17:02:21 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 17:02:21 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 17:02:21 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 17:02:21 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 17:02:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:02:24 INFO - Installing collected packages: mozsystemmonitor 17:02:24 INFO - Running setup.py install for mozsystemmonitor 17:02:24 INFO - Successfully installed mozsystemmonitor 17:02:24 INFO - Cleaning up... 17:02:24 INFO - Return code: 0 17:02:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:02:24 INFO - Using env: {'DISPLAY': ':0', 17:02:24 INFO - 'HOME': '/home/cltbld', 17:02:24 INFO - 'LANG': 'en_US.UTF-8', 17:02:24 INFO - 'LANGUAGE': 'en_US:en', 17:02:24 INFO - 'LOGNAME': 'cltbld', 17:02:24 INFO - 'MAIL': '/var/mail/cltbld', 17:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:24 INFO - 'MOZ_NO_REMOTE': '1', 17:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:24 INFO - 'NO_EM_RESTART': '1', 17:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:24 INFO - 'PWD': '/builds/slave/test', 17:02:24 INFO - 'SHELL': '/bin/bash', 17:02:24 INFO - 'SHLVL': '1', 17:02:24 INFO - 'TERM': 'linux', 17:02:24 INFO - 'TMOUT': '86400', 17:02:24 INFO - 'USER': 'cltbld', 17:02:24 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:24 INFO - '_': '/tools/buildbot/bin/python'} 17:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:24 INFO - Downloading/unpacking jsonschema==2.5.1 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:02:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:02:26 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 17:02:26 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 17:02:26 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 17:02:26 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 17:02:26 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 17:02:26 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 17:02:26 INFO - Downloading functools32-3.2.3-2.tar.gz 17:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:02:26 INFO - warning: no files found matching '*.txt' 17:02:26 INFO - no previously-included directories found matching 'build' 17:02:26 INFO - no previously-included directories found matching 'dist' 17:02:26 INFO - no previously-included directories found matching '.git*' 17:02:26 INFO - Installing collected packages: jsonschema, functools32 17:02:26 INFO - Running setup.py install for functools32 17:02:26 INFO - warning: no files found matching '*.txt' 17:02:26 INFO - no previously-included directories found matching 'build' 17:02:26 INFO - no previously-included directories found matching 'dist' 17:02:26 INFO - no previously-included directories found matching '.git*' 17:02:26 INFO - Successfully installed jsonschema functools32 17:02:26 INFO - Cleaning up... 17:02:26 INFO - Return code: 0 17:02:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:02: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')]} 17:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02: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')]} 17:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:26 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:26 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 17:02:26 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 17:02:26 INFO - Using env: {'DISPLAY': ':0', 17:02:26 INFO - 'HOME': '/home/cltbld', 17:02:26 INFO - 'LANG': 'en_US.UTF-8', 17:02:26 INFO - 'LANGUAGE': 'en_US:en', 17:02:26 INFO - 'LOGNAME': 'cltbld', 17:02:26 INFO - 'MAIL': '/var/mail/cltbld', 17:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:26 INFO - 'MOZ_NO_REMOTE': '1', 17:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:26 INFO - 'NO_EM_RESTART': '1', 17:02:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:26 INFO - 'PWD': '/builds/slave/test', 17:02:26 INFO - 'SHELL': '/bin/bash', 17:02:26 INFO - 'SHLVL': '1', 17:02:26 INFO - 'TERM': 'linux', 17:02:26 INFO - 'TMOUT': '86400', 17:02:26 INFO - 'USER': 'cltbld', 17:02:26 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:26 INFO - '_': '/tools/buildbot/bin/python'} 17:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:02:27 INFO - Cleaning up... 17:02:27 INFO - Return code: 0 17:02:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:02:27 INFO - Using env: {'DISPLAY': ':0', 17:02:27 INFO - 'HOME': '/home/cltbld', 17:02:27 INFO - 'LANG': 'en_US.UTF-8', 17:02:27 INFO - 'LANGUAGE': 'en_US:en', 17:02:27 INFO - 'LOGNAME': 'cltbld', 17:02:27 INFO - 'MAIL': '/var/mail/cltbld', 17:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:27 INFO - 'MOZ_NO_REMOTE': '1', 17:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:27 INFO - 'NO_EM_RESTART': '1', 17:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:27 INFO - 'PWD': '/builds/slave/test', 17:02:27 INFO - 'SHELL': '/bin/bash', 17:02:27 INFO - 'SHLVL': '1', 17:02:27 INFO - 'TERM': 'linux', 17:02:27 INFO - 'TMOUT': '86400', 17:02:27 INFO - 'USER': 'cltbld', 17:02:27 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:27 INFO - '_': '/tools/buildbot/bin/python'} 17:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:27 INFO - Downloading/unpacking blobuploader==1.2.4 17:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:29 INFO - Downloading blobuploader-1.2.4.tar.gz 17:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:02:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:02:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:02:30 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 17:02:30 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 17:02:30 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 17:02:30 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 17:02:30 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 17:02:30 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 17:02:30 INFO - Downloading docopt-0.6.1.tar.gz 17:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:02:30 INFO - Installing collected packages: blobuploader, requests, docopt 17:02:30 INFO - Running setup.py install for blobuploader 17:02:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:02:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:02:30 INFO - Running setup.py install for requests 17:02:31 INFO - Running setup.py install for docopt 17:02:31 INFO - Successfully installed blobuploader requests docopt 17:02:31 INFO - Cleaning up... 17:02:31 INFO - Return code: 0 17:02:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:31 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')]} 17:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:31 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')]} 17:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:31 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:31 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 17:02:31 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 17:02:31 INFO - Using env: {'DISPLAY': ':0', 17:02:31 INFO - 'HOME': '/home/cltbld', 17:02:31 INFO - 'LANG': 'en_US.UTF-8', 17:02:31 INFO - 'LANGUAGE': 'en_US:en', 17:02:31 INFO - 'LOGNAME': 'cltbld', 17:02:31 INFO - 'MAIL': '/var/mail/cltbld', 17:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:31 INFO - 'MOZ_NO_REMOTE': '1', 17:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:31 INFO - 'NO_EM_RESTART': '1', 17:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:31 INFO - 'PWD': '/builds/slave/test', 17:02:31 INFO - 'SHELL': '/bin/bash', 17:02:31 INFO - 'SHLVL': '1', 17:02:31 INFO - 'TERM': 'linux', 17:02:31 INFO - 'TMOUT': '86400', 17:02:31 INFO - 'USER': 'cltbld', 17:02:31 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:31 INFO - '_': '/tools/buildbot/bin/python'} 17:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:31 INFO - Running setup.py (path:/tmp/pip-hRvKgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:31 INFO - Running setup.py (path:/tmp/pip-LeAlL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Running setup.py (path:/tmp/pip-iTKbet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Running setup.py (path:/tmp/pip-n8cXUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Running setup.py (path:/tmp/pip-BSnjUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Running setup.py (path:/tmp/pip-8tW02X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Running setup.py (path:/tmp/pip-cwxR0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:31 INFO - Running setup.py (path:/tmp/pip-CrRxRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:32 INFO - Running setup.py (path:/tmp/pip-vj2ztQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:32 INFO - Running setup.py (path:/tmp/pip-nYXR1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:32 INFO - Running setup.py (path:/tmp/pip-OPZeiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:32 INFO - Running setup.py (path:/tmp/pip-IPrKD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Running setup.py (path:/tmp/pip-wyz13l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Running setup.py (path:/tmp/pip-M_Jwna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Running setup.py (path:/tmp/pip-rDNdcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Running setup.py (path:/tmp/pip-Lft1gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Running setup.py (path:/tmp/pip-kySseM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:02:32 INFO - Running setup.py install for manifestparser 17:02:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Running setup.py install for mozcrash 17:02:32 INFO - Running setup.py install for mozdebug 17:02:32 INFO - Running setup.py install for mozdevice 17:02:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozfile 17:02:33 INFO - Running setup.py install for mozhttpd 17:02:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozinfo 17:02:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozInstall 17:02:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozleak 17:02:33 INFO - Running setup.py install for mozlog 17:02:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for moznetwork 17:02:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozprocess 17:02:33 INFO - Running setup.py install for mozprofile 17:02:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozrunner 17:02:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozscreenshot 17:02:34 INFO - Running setup.py install for moztest 17:02:34 INFO - Running setup.py install for mozversion 17:02:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:02:34 INFO - Cleaning up... 17:02:34 INFO - Return code: 0 17:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:34 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')]} 17:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:34 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')]} 17:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:34 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:34 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 17:02:34 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 17:02:34 INFO - Using env: {'DISPLAY': ':0', 17:02:34 INFO - 'HOME': '/home/cltbld', 17:02:34 INFO - 'LANG': 'en_US.UTF-8', 17:02:34 INFO - 'LANGUAGE': 'en_US:en', 17:02:34 INFO - 'LOGNAME': 'cltbld', 17:02:34 INFO - 'MAIL': '/var/mail/cltbld', 17:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:34 INFO - 'MOZ_NO_REMOTE': '1', 17:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:34 INFO - 'NO_EM_RESTART': '1', 17:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:34 INFO - 'PWD': '/builds/slave/test', 17:02:34 INFO - 'SHELL': '/bin/bash', 17:02:34 INFO - 'SHLVL': '1', 17:02:34 INFO - 'TERM': 'linux', 17:02:34 INFO - 'TMOUT': '86400', 17:02:34 INFO - 'USER': 'cltbld', 17:02:34 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:34 INFO - '_': '/tools/buildbot/bin/python'} 17:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:34 INFO - Running setup.py (path:/tmp/pip-yiqYd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:34 INFO - Running setup.py (path:/tmp/pip-KaR_wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:34 INFO - Running setup.py (path:/tmp/pip-fxdLCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:34 INFO - Running setup.py (path:/tmp/pip-GNKM62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:34 INFO - Running setup.py (path:/tmp/pip-lr1o3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:35 INFO - Running setup.py (path:/tmp/pip-aOahTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:35 INFO - Running setup.py (path:/tmp/pip-447rAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:35 INFO - Running setup.py (path:/tmp/pip-eUfq2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:35 INFO - Running setup.py (path:/tmp/pip-KR7z_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:35 INFO - Running setup.py (path:/tmp/pip-KMyiJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:35 INFO - Running setup.py (path:/tmp/pip-WUUIpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:35 INFO - Running setup.py (path:/tmp/pip-naTpA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:35 INFO - Running setup.py (path:/tmp/pip-D5THpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:35 INFO - Running setup.py (path:/tmp/pip-qmZjVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:35 INFO - Running setup.py (path:/tmp/pip-tnotkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:35 INFO - Running setup.py (path:/tmp/pip-kVALkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:35 INFO - Running setup.py (path:/tmp/pip-rkFVyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:35 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)) 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:35 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)) 17:02:35 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)) 17:02:35 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)) 17:02:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:02:35 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 17:02:35 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 17:02:35 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 17:02:35 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 17:02:35 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 17:02:35 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 17:02:37 INFO - Downloading blessings-1.6.tar.gz 17:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:02:38 INFO - Installing collected packages: blessings 17:02:38 INFO - Running setup.py install for blessings 17:02:38 INFO - Successfully installed blessings 17:02:38 INFO - Cleaning up... 17:02:38 INFO - Return code: 0 17:02:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:02:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:38 INFO - Reading from file tmpfile_stdout 17:02:38 INFO - Current package versions: 17:02:38 INFO - argparse == 1.2.1 17:02:38 INFO - blessings == 1.6 17:02:38 INFO - blobuploader == 1.2.4 17:02:38 INFO - docopt == 0.6.1 17:02:38 INFO - functools32 == 3.2.3-2 17:02:38 INFO - jsonschema == 2.5.1 17:02:38 INFO - manifestparser == 1.1 17:02:38 INFO - mozInstall == 1.12 17:02:38 INFO - mozcrash == 1.0 17:02:38 INFO - mozdebug == 0.1 17:02:38 INFO - mozdevice == 0.48 17:02:38 INFO - mozfile == 1.2 17:02:38 INFO - mozhttpd == 0.7 17:02:38 INFO - mozinfo == 0.9 17:02:38 INFO - mozleak == 0.1 17:02:38 INFO - mozlog == 3.4 17:02:38 INFO - moznetwork == 0.27 17:02:38 INFO - mozprocess == 0.23 17:02:38 INFO - mozprofile == 0.28 17:02:38 INFO - mozrunner == 6.13 17:02:38 INFO - mozscreenshot == 0.1 17:02:38 INFO - mozsystemmonitor == 0.3 17:02:38 INFO - moztest == 0.8 17:02:38 INFO - mozversion == 1.4 17:02:38 INFO - psutil == 3.1.1 17:02:38 INFO - requests == 1.2.3 17:02:38 INFO - wsgiref == 0.1.2 17:02:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:38 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:38 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 17:02:38 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 17:02:38 INFO - Using env: {'DISPLAY': ':0', 17:02:38 INFO - 'HOME': '/home/cltbld', 17:02:38 INFO - 'LANG': 'en_US.UTF-8', 17:02:38 INFO - 'LANGUAGE': 'en_US:en', 17:02:38 INFO - 'LOGNAME': 'cltbld', 17:02:38 INFO - 'MAIL': '/var/mail/cltbld', 17:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:38 INFO - 'MOZ_NO_REMOTE': '1', 17:02:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:38 INFO - 'NO_EM_RESTART': '1', 17:02:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:38 INFO - 'PWD': '/builds/slave/test', 17:02:38 INFO - 'SHELL': '/bin/bash', 17:02:38 INFO - 'SHLVL': '1', 17:02:38 INFO - 'TERM': 'linux', 17:02:38 INFO - 'TMOUT': '86400', 17:02:38 INFO - 'USER': 'cltbld', 17:02:38 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:38 INFO - '_': '/tools/buildbot/bin/python'} 17:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 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)) 17:02:38 INFO - Cleaning up... 17:02:38 INFO - Return code: 0 17:02:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:38 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 0x7f839bc3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ba3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, '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': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', '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 17:02:38 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 17:02:38 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 17:02:38 INFO - Using env: {'DISPLAY': ':0', 17:02:38 INFO - 'HOME': '/home/cltbld', 17:02:38 INFO - 'LANG': 'en_US.UTF-8', 17:02:38 INFO - 'LANGUAGE': 'en_US:en', 17:02:38 INFO - 'LOGNAME': 'cltbld', 17:02:38 INFO - 'MAIL': '/var/mail/cltbld', 17:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:38 INFO - 'MOZ_NO_REMOTE': '1', 17:02:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:38 INFO - 'NO_EM_RESTART': '1', 17:02:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:38 INFO - 'PWD': '/builds/slave/test', 17:02:38 INFO - 'SHELL': '/bin/bash', 17:02:38 INFO - 'SHLVL': '1', 17:02:38 INFO - 'TERM': 'linux', 17:02:38 INFO - 'TMOUT': '86400', 17:02:38 INFO - 'USER': 'cltbld', 17:02:38 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:38 INFO - '_': '/tools/buildbot/bin/python'} 17:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:02:38 INFO - Cleaning up... 17:02:38 INFO - Return code: 0 17:02:38 INFO - Running post-action listener: _resource_record_post_action 17:02:38 INFO - Running post-action listener: _start_resource_monitoring 17:02:38 INFO - Starting resource monitoring. 17:02:38 INFO - [mozharness: 2017-01-12 01:02:38.911721Z] Finished create-virtualenv step (success) 17:02:38 INFO - [mozharness: 2017-01-12 01:02:38.912224Z] Running install step. 17:02:38 INFO - Running pre-action listener: _resource_record_pre_action 17:02:38 INFO - Running main action method: install 17:02:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:39 INFO - Reading from file tmpfile_stdout 17:02:39 INFO - Detecting whether we're running mozinstall >=1.0... 17:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:02:39 INFO - Reading from file tmpfile_stdout 17:02:39 INFO - Output received: 17:02:39 INFO - Usage: mozinstall [options] installer 17:02:39 INFO - Options: 17:02:39 INFO - -h, --help show this help message and exit 17:02:39 INFO - -d DEST, --destination=DEST 17:02:39 INFO - Directory to install application into. [default: 17:02:39 INFO - "/builds/slave/test"] 17:02:39 INFO - --app=APP Application being installed. [default: firefox] 17:02:39 INFO - mkdir: /builds/slave/test/build/application 17:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:02:55 INFO - Reading from file tmpfile_stdout 17:02:55 INFO - Output received: 17:02:55 INFO - /builds/slave/test/build/application/firefox/firefox 17:02:55 INFO - Running post-action listener: _resource_record_post_action 17:02:55 INFO - [mozharness: 2017-01-12 01:02:55.167825Z] Finished install step (success) 17:02:55 INFO - [mozharness: 2017-01-12 01:02:55.168067Z] Running run-tests step. 17:02:55 INFO - Running pre-action listener: _resource_record_pre_action 17:02:55 INFO - Running main action method: run_tests 17:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:02:55 INFO - Python 2.7.3 17:02:55 INFO - Return code: 0 17:02:55 INFO - grabbing minidump binary from tooltool 17:02: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')]} 17:02:55 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 0x2ba0f60>, '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 0x2ba1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ba21d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:02:55 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 17:02:55 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 17:02:55 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 17:02:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:02:55 INFO - Return code: 0 17:02:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:02:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:02:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 17:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 17:02:55 INFO - Using env: {'DISPLAY': ':0', 17:02:55 INFO - 'HOME': '/home/cltbld', 17:02:55 INFO - 'LANG': 'en_US.UTF-8', 17:02:55 INFO - 'LANGUAGE': 'en_US:en', 17:02:55 INFO - 'LOGNAME': 'cltbld', 17:02:55 INFO - 'MAIL': '/var/mail/cltbld', 17:02:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:02:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:55 INFO - 'MOZ_NO_REMOTE': '1', 17:02:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:02:55 INFO - 'NO_EM_RESTART': '1', 17:02:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:55 INFO - 'PWD': '/builds/slave/test', 17:02:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:02:55 INFO - 'SHELL': '/bin/bash', 17:02:55 INFO - 'SHLVL': '1', 17:02:55 INFO - 'TERM': 'linux', 17:02:55 INFO - 'TMOUT': '86400', 17:02:55 INFO - 'USER': 'cltbld', 17:02:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407', 17:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:55 INFO - '_': '/tools/buildbot/bin/python'} 17:02:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 17:02:55 INFO - mozversion application_buildid: 20170111152017 17:02:55 INFO - mozversion application_changeset: 11086d0d2e8e3451bc9223cc5470538d1c65b718 17:02:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:02:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:02:55 INFO - mozversion application_name: Firefox 17:02:55 INFO - mozversion application_remotingname: firefox-dev 17:02:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:02:55 INFO - mozversion application_vendor: Mozilla 17:02:55 INFO - mozversion application_version: 52.0a2 17:02:55 INFO - mozversion platform_buildid: 20170111152017 17:02:55 INFO - mozversion platform_changeset: 11086d0d2e8e3451bc9223cc5470538d1c65b718 17:02:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:02:55 INFO - mozversion platform_version: 52.0a2 17:02:55 INFO - using testdate: 1484182975 17:02:55 INFO - actual date: 1484182975 17:02:55 INFO - starting webserver on 'localhost:57027' 17:02:55 INFO - SUITE-START | Running 2 tests 17:02:55 INFO - TEST-START | dromaeo_css 17:02:55 INFO - operating with platform_type : linux_ 17:02:55 INFO - Initialising browser for dromaeo_css test... 17:02:55 INFO - TEST-INFO | started process 5420 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT8rW4s/profile http://localhost:57027/getInfo.html) 17:02:59 INFO - PROCESS | 5420 | __metrics Screen width/height:1600/1200 17:02:59 INFO - PROCESS | 5420 | colorDepth:24 17:02:59 INFO - PROCESS | 5420 | Browser inner width/height: 1024/697 17:02:59 INFO - PROCESS | 5420 | __metrics 17:02:59 INFO - PROCESS | 5420 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:02:59 INFO - PROCESS | 5420 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:03:00 INFO - PROCESS | 5420 | console.error: PushService: 17:03:00 INFO - PROCESS | 5420 | stateChangeProcessEnqueue: Error transitioning state 17:03:00 INFO - PROCESS | 5420 | UnknownError 17:03:01 INFO - TEST-INFO | 5420: exit 0 17:03:01 INFO - Browser initialized. 17:03:01 INFO - Running cycle 1/1 for dromaeo_css test... 17:03:01 INFO - TEST-INFO | started process 5513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT8rW4s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:12 INFO - PROCESS | 5513 | RSS: Main: 143904768 17:03:12 INFO - PROCESS | 5513 | 17:06:14 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57027/tests/dromaeo/cssquery-ext.html) 17:06:15 INFO - PROCESS | 5513 | RSS: Main: 247939072 17:06:15 INFO - PROCESS | 5513 | 17:09:18 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-ext.html (next: http://localhost:57027/tests/dromaeo/cssquery-jquery.html) 17:09:18 INFO - PROCESS | 5513 | RSS: Main: 225255424 17:09:18 INFO - PROCESS | 5513 | 17:12:20 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57027/tests/dromaeo/cssquery-mootools.html) 17:12:21 INFO - PROCESS | 5513 | RSS: Main: 173338624 17:12:21 INFO - PROCESS | 5513 | 17:15:23 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57027/tests/dromaeo/cssquery-prototype.html) 17:15:23 INFO - PROCESS | 5513 | RSS: Main: 173170688 17:15:23 INFO - PROCESS | 5513 | 17:18:26 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57027/tests/dromaeo/cssquery-yui.html) 17:18:26 INFO - PROCESS | 5513 | RSS: Main: 176971776 17:18:26 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | Cycle 1(1): loaded http://localhost:57027/tests/dromaeo/cssquery-yui.html (next: http://localhost:57027/tests/dromaeo/cssquery-dojo.html) 17:21:29 INFO - PROCESS | 5513 | RSS: Main: 257335296 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | __start_tp_report 17:21:29 INFO - PROCESS | 5513 | _x_x_mozilla_page_load 17:21:29 INFO - PROCESS | 5513 | _x_x_mozilla_page_load_details 17:21:29 INFO - PROCESS | 5513 | |i|pagename|runs| 17:21:29 INFO - PROCESS | 5513 | |0;dojo.html;3180.2208458927566;3187.9521807172896;3200.8879689210867;3223.838808059599;3243.497257925021;5472.835814925548;5554.361248456429;5569.303837020372;5584.888302233953;5607.579431542636;5555.527780138693;5626.071698169807;5628.915566266509;5631.662100273976;5651.2935883014625;5037.992401519692;5066.518680725344;5094.057599344114;5144.8971020579565;5183.067047931371;4957.578605818491;4990.351254336949;5131.102057140004;5179.8446046618665;5182.455842136578;5054.9241761373605;5060.291559181717;5063.088644044071;5106.923396149061;5137.229415587654;5181.974090129563;5194.68831870089;5194.948050519484;5198.194279886624;5227;4818.156822556039;5078.64449488536;5089.389273287229;5120.411152717448;5162.896742065138;5378.704171270579;5524.613277070606;5551.861203469905;5560.4161563035705;5566.471185237437;5976.432238937337;6031.577789554733;6085.6348619083;6089.786857460012;6104.664243466607;5766.452187042224;6123.020316749344;6131.846703832395;6132.6933653317155;6140.785072522441;5416.702081385522;5563.805266815682;5612.719364031782;5620.662760234399;5628;6140.938590614126;6144.293406258337;6150.969245153746;6165.229346331708;6166.321704612489;5646.096624540054;5921.319048309413;5937.168796368512;5943.375945525658;5948.910766338508;5773.855653608609;5999.130126131687;6050.001749711337;6058.818235452944;6124.989376752786;6084.056971169476;6089.269287685417;6100.298465676416;6101.908471372933;6130.111133885562;6065.272167339859;6099.9085013724825;6106.786262480792;6122.142899993915;6130.233720784902;4468.106378724203;4501.504834468205;4625.791839367236;4663.020765639186;4665.95334046662;4143.81352839117;4169.645580125723;4180.038591123997;4191.622753952159;4208.410822484854;5734.053881109654;5736.827895163152;5742.598018138774;5744.425557444222;5751.85620359486;5770.826875193751;5817.476427121494;5823.708814559255;5954.910676339859;6097.7865774697675;5630.662160270397;5667.914981275202;5671;5681.857953551194;5701.857453563694;5207.427183009866;5208.296879921238;5211.296474975906;5215.139501982207;5237.6333656643665;5797.739101740432;5905.704714764331;6080.300765412034;6125.295591007091;6138.386161383826;6015.977283861842;6088.208532890696;6115.970584430425;6116.633002019893;6134.2332208473945;6154.628180721825;6169.629822210682;6189.133521306931;6190.597611155261;6196.597221180609;5601.6358936668985;5614.9719251403485;5952.315483719428;5987.491063259672;5988.191594134911;5367.248585198154;5922.022866227025;5990.460858522754;6069.544784141295;6117.724702388314;5885.735141918538;5917.526597872074;5920.703964801656;5921.437463440966;5929.881402372014;5596.076647353141;5902.61632993854;5910.674912879833;5914.34942156371;5932.466078052996;5702.37273899879;5810.709464526842;5842.38654941242;5865.442782935615;5889;5991.490723288569;5995.760169593342;5996.70016499182;5998.970005150121;6001.36985616504;5527.115661494142;5575.721213939368;5694.601377903507;5840.445157710011;5841.18223448726 17:21:29 INFO - PROCESS | 5513 | |1;ext.html;582.6591444005256;601.0383386581469;625.121704005872;653.5849735418008;667.4393509452059;34489.137771555696;45435.77282113589;46216.537834621646;46273.30590041152;49767.25349119757;47010;47412.762936185354;47440.76279618597;47464.525354746445;47476.762616186956;7986.281234688877;8842.823143537142;8987.955060224689;8999.460032398045;9001.144891235323;8210.30212431944;10458.52936617854;10706.41114738689;10707.143428525738;11048.668539943814;11013.779724405507;11072;11083.833742493869;11085.27945683532;11093;10826.133909287239;10863.67408977732;10877.401742904138;10881.673549793519;10896.891031089706;10756.73108172294;10808.51361688726;10817.78364432707;10833;10841.728956776065;43338.78330608327;43392.78303608462;43446.78276608597;43449.56550434487;43798.12403751939;3888.6889048876023;3921.1765529238896;3925.568187499373;4103.876883693494;4382.320740285261;33029.50455743166;33044;33166.66833331684;33365.33269334624;33669.663303367146;4538.205813982539;4556.3621093047;4598.779915532615;4636.976815115937;4701.929471057937;346335;346892;348265;348965.255173725;349358;62589;63057;63065;63148.36851631517;63151.684241579416;39611;41169.58830411657;41196.58803411987;65145;65879;18942.810571894104;19038.428847134608;19089.90455047716;19146.52133696641;19161;13186;13492.853107485973;14249.145051296955;14411.279436028157;14527.418903243963;20449.182032718523;20717.68923466149;20727.170913163303;20755;20759.377218683465;7585.241475852371;7639.961800190964;7683.884741728879;7763.844723105506;7802.141764405911;1052.6052730226165;1058.222206678091;1061.3472714280815;1097.3251450357973;1116.603605719974;34047.82976085104;34148.82925585356;34324;34636.13409664728;34824.12939676478;1122.8490796933208;1170.7365842685329;1215.5380955236953;1239.429862263351;1272.41468924294;17433.47699569015;17988.127835628686;18003.917786152546;18044.639107217783;18127.546811329823;540767;543712;547101;547264;549401;58883.70558147182;59416;59443;59504.70247648734;59556.70221648864;1289.6002239305844;1356.647271709343;1380.4892189889806;1434.0535246737104;1449.8695117439481;32035.679643203268;35020.64979350174;35141.47287790736;37146.62853371432;37177;56271.71864140653;56770.99999999835;56844.431555683914;56875.43124568701;56910.14634780565;439530;440876;441196;441890;442217;25847.741522584533;30560.388792223588;30774.84612576923;30813;32328.35343293168;48448.757756210995;48449;48968;49026;49072.263916040574;40612.79693601513;41218;41414;42367.788161059;42690;41732.79133604313;42235.36646950237;42614.78692606641;43251.48619798418;43776.124477509635;43148.56851431446;43452.78273608612;43518.564814352714;43537.129257415305;43571;36729.63270367369;38567.2154216965;38793.67994490359;38802.41796373113;39414.80292598633;29311.7068829309;29676.554851677673;29841.701582983893;30077;30320.696793031788 17:21:29 INFO - PROCESS | 5513 | |2;jquery.html;421.65845665011364;422.2736892544826;429.59617959118435;431.69565456353274;446.58467625108636;18562.443126706203;18878.811211887878;20175.192992280292;20225.292114775984;20311.898440507794;19168.52078698033;19346.709799353022;19534.31629892954;19614.901925490354;19617.803821961777;5586.162075688659;5824.330202026778;5830.09633506805;5834.474897259246;5840.415958404147;5526.364468086182;5642.294713160855;5681.602287839853;5719.999000174974;5726.7422965966425;5670.206171136024;5679.7160141992945;5685.886282274352;5687.374388817227;5701.572382071361;5571.4428557144365;5622.072358060915;5671.319441667006;5673.631213971079;5676.829695109112;5172.6379153459275;5172.948270517284;5174.767135478913;5176.7411629418375;5182.404023537304;23779;23841.88079059611;23981.640275395883;24062.879685601634;24202.39494012646;2519.5212909547126;2575.25317657879;2700.39241170737;2890.7398334149816;3228.4834426491648;14938.178400187986;15160.09039457636;15230.543083707396;15318.004329718533;15445.459408920746;1899.278274255774;1975.8518111141723;1983.880967141976;1984.791596882321;2022.6662600670873;57559;68142;78624.60687696582;83399;83701.58149209277;11122.721931951766;11220.551177952953;11416.31502109876;11511.539538418538;11611.954924056876;16354.182290885408;16358.182090895407;16370.67258654844;16403.179841007903;16411.42560010394;8613.267872230805;8675.132486751274;8693.782655433664;8816.559172041501;8967.865482017774;8483.44857584255;8496.065432802387;8497.61760720769;8684.131586841266;8721.825563488821;6924.3421874921805;6934.930650693428;7016.894746578805;7025.716642091239;7040.859182816315;1960.1865225931078;1975.8221760041665;2054.856160068811;2069.793020697918;2089.3731880435807;830.8546004449197;950.933434659581;970.7912798748303;1001.8246806808779;1046.2257929132388;20045.298414555713;20221.39335819928;20481.38555843327;20986.47533811667;21092.261770838253;1026.1329176845554;1045.17953406577;1045.8954104589632;1110.1008183371496;1122.315387613555;11101.500432480558;11663.475143618558;11726.648200553997;11766.411679415995;11934.164608477497;384107;435396;449878;452097;467708;89361;90120.54939725259;90359;90635.54682226678;91222;1038.1020417339098;1040.2977989856815;1064.744461329291;1071.0253669161027;1073.7691396349821;17662.558436039202;17724.734128988333;18178.36375726843;18227;18282.817171828112;41284;42257.366139508544;42388.576114238465;42662;43038.35442468303;56458.153127702295;57305;57468;57756.71121644365;58231;8653.61058752345;8869.822603547764;8901.154390332907;9051.04963978799;9051.27589792828;13237.933810330887;13460.46158153702;13501.59495215145;13928;14039.929800350932;11966.521339146337;12180.939095304468;12248.571300004458;12319.568815091428;12596.874031259571;11671.59149429766;11820.172587918763;11948.342841143472;11955.163138580216;12042.879571204527;10495.947520262655;12045.33750643723;12300.569480068154;12315.384230788246;12671.493140274655;8392.32861371101;8547.871781923152;8603.397762156588;8614.44006139599;8935.061818509223;11545.826812597987;11555.2489088209;11578.884211157781;11591.362475063952;11600.709982250512 17:21:29 INFO - PROCESS | 5513 | |3;mootools.html;1498.146056747654;1529.0825504697182;1676.6730487554923;1806.9909650451746;1810.5202121437812;8497.787555311113;9054.366194366397;9055.637774489012;9057.320700947423;9069.818603627931;7100.289971002897;7283.599402032886;7295.161056478507;7462.962685186567;8931.285497160228;2429.6112621980487;2529.5067461844856;2537.0486067724605;2538.2892790018823;2548.1336345642476;2500.3374105861963;2511.522810665977;2544.0332673584103;2565.1663209456906;2565.730598287181;2517.987410062947;2520.8865601047996;2544.3384719972846;2546.796256299501;2550.1584477122597;2470.987645061772;2488.5147396257735;2488.912888048919;2490.9128180513685;2493.7256901740793;2465.790407815338;2509.3224829295987;2511.849289042645;2512.0454227393657;2512.4095837478176;8534.829303413959;8616.224539791448;8619.784505387353;8632.482051076955;8634.438761480484;5488.313960754906;5624.5781566382275;5632.633878797865;5633.8309850704545;5639.351474580435;3313.337332533503;3380.188754698879;3487.093355727504;3520.6479352064844;3525.6121826599056;1122.3490375582144;1130.7681925205313;1132.6610549215843;1132.971675708114;1183.089021453476;12078.335691536962;12292.262464252173;12321.5071397144;12333.50665973369;12361.75276494474;4776.8566942991765;5018.3476148101245;5103;5111.258867464197;5135.101357262464;10562.419066951237;10569.524371403306;10584.259101862795;10584.629537966135;10598.04617584421;5698.914516282259;5700.173474846124;5703.17303990919;5704.828855134353;5707.8858422832145;4308.80610372534;5017.147084995559;5061.34202553673;5098.694078355311;5126.55374508925;3819.236152769457;4018.959810401917;4156.605122513356;4159.022629682022;4204.0540878302745;1387.668716556175;1435.2321507993174;1437.4681367893945;1444.3572610188576;1462.4442711769673;912.9680461183826;948.4309414351334;949.9382540134869;954.3367359685091;1061.0185578340004;5923.822285331447;5956.761729530857;6110.877782444326;6177.84555386119;6213.378662133841;985.1478471122571;1003.598560575761;1034.384541197986;1035.53400969564;1059.7456610413444;4253.149370125987;4457.465104187519;4475.7985890635;4491.708038977456;4506.639468842484;10576.682699519026;10801.027907488364;10874.72813179661;10907.236493445384;10924.071453926505;10345.37927724339;10840.241183117107;10847.51186196623;10862.61980830683;10871;8223.753287401387;8412.62143203545;8414.915850841535;8414.957925210334;8419.663213471393;5989.131575921379;6073.30157031947;6180.81457556274;6254.249490061222;6278;9800.558974846292;9807.215422766305;9897.554610042422;9938.304318697621;9950.701478955643;9867.555959981675;9934.503274836085;9987.800243995225;10385.948070259903;10396;6574.737010519526;7968.920310796818;8125.349972002344;8141.592920354077;8144.633491492778;7227.457940654577;8230.259276665129;8384.790380240298;8460.323174145931;8584.527850968258;7399.704011839466;7407.66665500043;7461.067366579177;7596.164421913474;7631.69473221065;7006.824829379102;7116.9644151780985;7188.820279492846;7228.530145540523;7525.510841795266;7115.252898445796;7154.892676609959;7161.892571611325;7170.282971702788;7173.784786456415;5481.342238931354;5557.72211389437;5565.554755619622;5626;5708.058170401837;5309.36287645485;5451.400345961862;5627.071533196977;5633.577481688972;5647.943520564906 17:21:29 INFO - PROCESS | 5513 | |4;prototype.html;358.51779356765155;365.1145971020275;366.421054733521;369.09571549703236;371.0667670807917;5334.5999050071205;5402.405735369106;5417.889332686799;5430.429804870491;5438.673679579228;5264.229357934832;5323.976888782267;5361.839144825652;5369.650972686773;5385.21914322423;4209.810558524874;4215.51521575019;4223.556526564713;4253.787310634471;4273.764942928137;4233.089885674579;4241.36379543068;4248.874048377183;4269.679774016946;4297.419848320484;4249.1714115747445;4256.531781504032;4258.212230737308;4271.273883439807;4274.658027357804;4146.310041720758;4258.403823464718;4259.403683484315;4260.275753121977;4307.504636966742;3783.754055986352;3788.71584631151;3796.9620303796883;3798.4682144499516;3801.4107813288983;5199.4401679496;5211.530962213381;5226.634135610509;5232.738363081869;5258.291055407008;4730.741859550805;4804.159272127364;4845.588125009378;4873.463918968911;4897.706137631718;5519.889602207973;5562;5570.777168913239;5596.748146333384;5654.208410822447;4227.3447615619325;4235.597618226295;4239.533651298386;4262.893427664333;4271.1671224111315;5600.047991841397;5822.912656310159;5855.3266374367495;5945.524358051389;5952.3154837193415;4237.639800616982;4240.257954857888;4260.297050986553;4260.424842646203;4263.211305908417;4296.656267498593;4301.849435269751;4304.246756817607;4309.59058889405;4317.266064768997;4705.341252224691;4743.430788305425;4756;4905.117078925828;4911.656184067081;5571.161516699561;5612.382637909826;5624.887502249932;5630.127330263816;5656.575756818255;1410.7460657081824;1420.2259768426231;1422.5945605502588;1422.8079209306818;1430.6351880270672;943.8065196634674;990.9454979976026;996.3722854601555;1003.7641154328727;1006.2302338710892;5186.377634683787;5196.662216955886;5215.400228973641;5228.581713462914;5235.042201156334;5488.874780669953;5514.503694667499;5529.0924631002235;5541.196526503634;5545.639533430395;4960.404751429852;5162.889978654607;5220.903610241816;5224.92162617561;5243.632945693841;2948.764098872085;2959.5708622249654;2964.570137330075;2972.271793410585;2983.418233444501;5669.262995810601;5674.347450043216;5682.920245153407;5697.857553561194;5707.34365547959;5385.138377859524;5613.859653508695;5617.522510586646;5661.547076233891;5669.858253543612;5708.602386997471;5760.481556659921;5903.0260007099205;5916.319623243296;5919.704014799329;5709.857253568694;5720.885582288414;5738.8278351649515;5738.971305143447;5761.539076873756;5539.639923405127;5645.943540564542;5711.400302968129;5712.37163911961;5743.8564035899435;5843.678597677169;5844.3571207168025;5868.882622347614;5871.2073870027;5873.765049397976;4204.831806727697;4209.515905670887;4211.136716973075;4221;4243.893902652459;5577.247071645276;5722.484976352148;5725.8568535786935;5752.856178595402;5755.971220143872;5558.6648011192965;5588.552915766811;5589.273394458694;5593.965116453469;5681.772729090955;5401;5610.382857885547;5628.8874222514505;5647.20939068539;5691.8861622768145;5573.080441727069;5578.246936663661;5595.916061259166;5597.496225339739;5606.635568688022;5653.802116925888;5659.179418984307;5662.480196312766;5668.51817595509;5669.121286200645;5340.385855626652;5507.586930980273;5513.050517973025;5522.420145884625;5536.501714845684 17:21:29 INFO - PROCESS | 5513 | |5;yui.html;771.090113665874;779.0301075161425;805.5086397297647;836.6695155413612;846.3609974469275;14396.208208548525;14490.275486225699;14594;15071.246437678114;16019.519414417571;13353;14593.197374144444;14832;14857.257137143153;14863.108213507197;812.1107387410791;850.698002209213;851.093585331623;852.5694524265238;852.9189726975958;841.8947631546057;844.3582877013483;844.3962566764762;844.4257904624852;844.666356789068;894.8881389826272;899.874017637528;899.874017637528;900.0909081827336;900.1853322742895;891.1667590802621;892.5358813416987;892.8616064510044;893.1113542025694;893.9329550283754;654.4241067860253;655.0338251079619;655.2137435077877;655.2922843329193;655.4919937048825;13161.605151845364;13230.404631791593;13421;14094.647633809134;14103.435862565486;14789.926050369786;14980.475683351031;15160;15548.455804046804;15596;12325.69185770354;12414.379281035912;12455.128141030042;12456.501739930392;12475.438605262785;3865.2849222893574;3928.64642182205;3962.643362097425;4022.1352409231863;4033.294173519652;56777.71611141959;58316.416835831944;58467.415325846625;62987.37012629861;66209;12028.338441385808;12160;12177.269363838199;12195.146339756131;12196.39010621697;20837.68743468849;20970;20981.580368392853;21335.893320533607;21720.23979160722;8964.551772411354;8993.685221017233;9072.364934454525;9182.816343673088;9222.492762897971;4735.171345014609;4841.806327746851;4925.1873440881855;5001.249812528077;5036.395632524047;3543.698785603201;3655.4882316475714;3658.414653655402;3658.8719394821055;3662.7619204751604;624.6970219443566;636.4812677667686;641.8010416770816;642.7979678002166;662.1656712542232;792.7780221537863;802.6348011654683;805.9073206581318;809.9757007289791;826.182079741052;9880.35777674449;9883.110520053086;9955.30312878106;10492.737681558023;10507.94746026265;672.4183581202225;749.8050506868144;756.7313603670727;788.3444765991372;808.6078252047752;4621.353010578522;4987.326710893983;4994.575461085776;5021.372328458942;5029.84910452687;55474.72262638661;75930.24069759232;76160.61919690476;79688.601556993;82560.58719706364;48541.757291214024;68187;70889;73052.26947730561;73925;65116.34883651198;66735.66632166905;67012.3298767006;67021.6648916762;69048.3095169052;9908.851367229641;10420.791584168426;10511.369317840954;10584.364938103738;10604.204684648528;45573.31640025336;46715.29927051165;46903.29645055395;46973.295400568335;47079.52920470751;37395.81302093472;38059.42910856284;55261;55799;56424.43575564192;12587;12944.93527532394;13024.413901374272;13387.397567109309;13508.121972071784;14261.500847470288;14656;14660.78008829847;14875.776863347275;15091.169985650895;13291.601251962456;13606.387712553156;13652.24912629815;14060.367283472473;14706.926465367605;13927.234002129575;13938.163710177421;14073.507427239998;14089.859101408854;14180.14919104857;13513.527026554024;13564.660883477993;14038.438462461387;14246.715065698421;14565.635859103608;11666.183367164216;11783.52865885355;11914.285142891107;11917.761644766882;12304.876951230373;11903.702407439538;11977.221480604081;12147.336596171319;12386.194897331645;12424 17:21:29 INFO - PROCESS | 5513 | __end_tp_report 17:21:29 INFO - PROCESS | 5513 | __start_cc_report 17:21:29 INFO - PROCESS | 5513 | _x_x_mozilla_cycle_collect,1030 17:21:29 INFO - PROCESS | 5513 | __end_cc_report 17:21:29 INFO - PROCESS | 5513 | __startTimestamp1484184089620__endTimestamp 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | ------- Summary: start ------- 17:21:29 INFO - PROCESS | 5513 | Number of tests: 6 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#0] dojo.html Cycles:165 Average:5573.60 Median:5744.43 stddev:636.10 (11.1%) stddev-sans-first:609.71 17:21:29 INFO - PROCESS | 5513 | Values: 3180.2 3188.0 3200.9 3223.8 3243.5 5472.8 5554.4 5569.3 5584.9 5607.6 5555.5 5626.1 5628.9 5631.7 5651.3 5038.0 5066.5 5094.1 5144.9 5183.1 4957.6 4990.4 5131.1 5179.8 5182.5 5054.9 5060.3 5063.1 5106.9 5137.2 5182.0 5194.7 5194.9 5198.2 5227.0 4818.2 5078.6 5089.4 5120.4 5162.9 5378.7 5524.6 5551.9 5560.4 5566.5 5976.4 6031.6 6085.6 6089.8 6104.7 5766.5 6123.0 6131.8 6132.7 6140.8 5416.7 5563.8 5612.7 5620.7 5628.0 6140.9 6144.3 6151.0 6165.2 6166.3 5646.1 5921.3 5937.2 5943.4 5948.9 5773.9 5999.1 6050.0 6058.8 6125.0 6084.1 6089.3 6100.3 6101.9 6130.1 6065.3 6099.9 6106.8 6122.1 6130.2 4468.1 4501.5 4625.8 4663.0 4666.0 4143.8 4169.6 4180.0 4191.6 4208.4 5734.1 5736.8 5742.6 5744.4 5751.9 5770.8 5817.5 5823.7 5954.9 6097.8 5630.7 5667.9 5671.0 5681.9 5701.9 5207.4 5208.3 5211.3 5215.1 5237.6 5797.7 5905.7 6080.3 6125.3 6138.4 6016.0 6088.2 6116.0 6116.6 6134.2 6154.6 6169.6 6189.1 6190.6 6196.6 5601.6 5615.0 5952.3 5987.5 5988.2 5367.2 5922.0 5990.5 6069.5 6117.7 5885.7 5917.5 5920.7 5921.4 5929.9 5596.1 5902.6 5910.7 5914.3 5932.5 5702.4 5810.7 5842.4 5865.4 5889.0 5991.5 5995.8 5996.7 5999.0 6001.4 5527.1 5575.7 5694.6 5840.4 5841.2 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#1] ext.html Cycles:180 Average:61714.12 Median:3923.37 stddev:119644.94 (3049.5%) stddev-sans-first:119892.54 17:21:29 INFO - PROCESS | 5513 | Values: 582.7 601.0 625.1 653.6 667.4 34489.1 45435.8 46216.5 46273.3 49767.3 47010.0 47412.8 47440.8 47464.5 47476.8 7986.3 8842.8 8988.0 8999.5 9001.1 8210.3 10458.5 10706.4 10707.1 11048.7 11013.8 11072.0 11083.8 11085.3 11093.0 10826.1 10863.7 10877.4 10881.7 10896.9 10756.7 10808.5 10817.8 10833.0 10841.7 43338.8 43392.8 43446.8 43449.6 43798.1 3888.7 3921.2 3925.6 4103.9 4382.3 33029.5 33044.0 33166.7 33365.3 33669.7 4538.2 4556.4 4598.8 4637.0 4701.9 346335.0 346892.0 348265.0 348965.3 349358.0 62589.0 63057.0 63065.0 63148.4 63151.7 39611.0 41169.6 41196.6 65145.0 65879.0 18942.8 19038.4 19089.9 19146.5 19161.0 13186.0 13492.9 14249.1 14411.3 14527.4 20449.2 20717.7 20727.2 20755.0 20759.4 7585.2 7640.0 7683.9 7763.8 7802.1 1052.6 1058.2 1061.3 1097.3 1116.6 34047.8 34148.8 34324.0 34636.1 34824.1 1122.8 1170.7 1215.5 1239.4 1272.4 17433.5 17988.1 18003.9 18044.6 18127.5 540767.0 543712.0 547101.0 547264.0 549401.0 58883.7 59416.0 59443.0 59504.7 59556.7 1289.6 1356.6 1380.5 1434.1 1449.9 32035.7 35020.6 35141.5 37146.6 37177.0 56271.7 56771.0 56844.4 56875.4 56910.1 439530.0 440876.0 441196.0 441890.0 442217.0 25847.7 30560.4 30774.8 30813.0 32328.4 48448.8 48449.0 48968.0 49026.0 49072.3 40612.8 41218.0 41414.0 42367.8 42690.0 41732.8 42235.4 42614.8 43251.5 43776.1 43148.6 43452.8 43518.6 43537.1 43571.0 36729.6 38567.2 38793.7 38802.4 39414.8 29311.7 29676.6 29841.7 30077.0 30320.7 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#2] jquery.html Cycles:180 Average:27775.09 Median:24132.64 stddev:72421.19 (300.1%) stddev-sans-first:72595.23 17:21:29 INFO - PROCESS | 5513 | Values: 421.7 422.3 429.6 431.7 446.6 18562.4 18878.8 20175.2 20225.3 20311.9 19168.5 19346.7 19534.3 19614.9 19617.8 5586.2 5824.3 5830.1 5834.5 5840.4 5526.4 5642.3 5681.6 5720.0 5726.7 5670.2 5679.7 5685.9 5687.4 5701.6 5571.4 5622.1 5671.3 5673.6 5676.8 5172.6 5172.9 5174.8 5176.7 5182.4 23779.0 23841.9 23981.6 24062.9 24202.4 2519.5 2575.3 2700.4 2890.7 3228.5 14938.2 15160.1 15230.5 15318.0 15445.5 1899.3 1975.9 1983.9 1984.8 2022.7 57559.0 68142.0 78624.6 83399.0 83701.6 11122.7 11220.6 11416.3 11511.5 11612.0 16354.2 16358.2 16370.7 16403.2 16411.4 8613.3 8675.1 8693.8 8816.6 8967.9 8483.4 8496.1 8497.6 8684.1 8721.8 6924.3 6934.9 7016.9 7025.7 7040.9 1960.2 1975.8 2054.9 2069.8 2089.4 830.9 950.9 970.8 1001.8 1046.2 20045.3 20221.4 20481.4 20986.5 21092.3 1026.1 1045.2 1045.9 1110.1 1122.3 11101.5 11663.5 11726.6 11766.4 11934.2 384107.0 435396.0 449878.0 452097.0 467708.0 89361.0 90120.5 90359.0 90635.5 91222.0 1038.1 1040.3 1064.7 1071.0 1073.8 17662.6 17724.7 18178.4 18227.0 18282.8 41284.0 42257.4 42388.6 42662.0 43038.4 56458.2 57305.0 57468.0 57756.7 58231.0 8653.6 8869.8 8901.2 9051.0 9051.3 13237.9 13460.5 13501.6 13928.0 14039.9 11966.5 12180.9 12248.6 12319.6 12596.9 11671.6 11820.2 11948.3 11955.2 12042.9 10495.9 12045.3 12300.6 12315.4 12671.5 8392.3 8547.9 8603.4 8614.4 8935.1 11545.8 11555.2 11578.9 11591.4 11600.7 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#3] mootools.html Cycles:180 Average:5865.96 Median:5130.83 stddev:3194.32 (62.3%) stddev-sans-first:3186.42 17:21:29 INFO - PROCESS | 5513 | Values: 1498.1 1529.1 1676.7 1807.0 1810.5 8497.8 9054.4 9055.6 9057.3 9069.8 7100.3 7283.6 7295.2 7463.0 8931.3 2429.6 2529.5 2537.0 2538.3 2548.1 2500.3 2511.5 2544.0 2565.2 2565.7 2518.0 2520.9 2544.3 2546.8 2550.2 2471.0 2488.5 2488.9 2490.9 2493.7 2465.8 2509.3 2511.8 2512.0 2512.4 8534.8 8616.2 8619.8 8632.5 8634.4 5488.3 5624.6 5632.6 5633.8 5639.4 3313.3 3380.2 3487.1 3520.6 3525.6 1122.3 1130.8 1132.7 1133.0 1183.1 12078.3 12292.3 12321.5 12333.5 12361.8 4776.9 5018.3 5103.0 5111.3 5135.1 10562.4 10569.5 10584.3 10584.6 10598.0 5698.9 5700.2 5703.2 5704.8 5707.9 4308.8 5017.1 5061.3 5098.7 5126.6 3819.2 4019.0 4156.6 4159.0 4204.1 1387.7 1435.2 1437.5 1444.4 1462.4 913.0 948.4 949.9 954.3 1061.0 5923.8 5956.8 6110.9 6177.8 6213.4 985.1 1003.6 1034.4 1035.5 1059.7 4253.1 4457.5 4475.8 4491.7 4506.6 10576.7 10801.0 10874.7 10907.2 10924.1 10345.4 10840.2 10847.5 10862.6 10871.0 8223.8 8412.6 8414.9 8415.0 8419.7 5989.1 6073.3 6180.8 6254.2 6278.0 9800.6 9807.2 9897.6 9938.3 9950.7 9867.6 9934.5 9987.8 10385.9 10396.0 6574.7 7968.9 8125.3 8141.6 8144.6 7227.5 8230.3 8384.8 8460.3 8584.5 7399.7 7407.7 7461.1 7596.2 7631.7 7006.8 7117.0 7188.8 7228.5 7525.5 7115.3 7154.9 7161.9 7170.3 7173.8 5481.3 5557.7 5565.6 5626.0 5708.1 5309.4 5451.4 5627.1 5633.6 5647.9 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#4] prototype.html Cycles:180 Average:4725.70 Median:5351.11 stddev:1352.43 (25.3%) stddev-sans-first:1315.90 17:21:29 INFO - PROCESS | 5513 | Values: 358.5 365.1 366.4 369.1 371.1 5334.6 5402.4 5417.9 5430.4 5438.7 5264.2 5324.0 5361.8 5369.7 5385.2 4209.8 4215.5 4223.6 4253.8 4273.8 4233.1 4241.4 4248.9 4269.7 4297.4 4249.2 4256.5 4258.2 4271.3 4274.7 4146.3 4258.4 4259.4 4260.3 4307.5 3783.8 3788.7 3797.0 3798.5 3801.4 5199.4 5211.5 5226.6 5232.7 5258.3 4730.7 4804.2 4845.6 4873.5 4897.7 5519.9 5562.0 5570.8 5596.7 5654.2 4227.3 4235.6 4239.5 4262.9 4271.2 5600.0 5822.9 5855.3 5945.5 5952.3 4237.6 4240.3 4260.3 4260.4 4263.2 4296.7 4301.8 4304.2 4309.6 4317.3 4705.3 4743.4 4756.0 4905.1 4911.7 5571.2 5612.4 5624.9 5630.1 5656.6 1410.7 1420.2 1422.6 1422.8 1430.6 943.8 990.9 996.4 1003.8 1006.2 5186.4 5196.7 5215.4 5228.6 5235.0 5488.9 5514.5 5529.1 5541.2 5545.6 4960.4 5162.9 5220.9 5224.9 5243.6 2948.8 2959.6 2964.6 2972.3 2983.4 5669.3 5674.3 5682.9 5697.9 5707.3 5385.1 5613.9 5617.5 5661.5 5669.9 5708.6 5760.5 5903.0 5916.3 5919.7 5709.9 5720.9 5738.8 5739.0 5761.5 5539.6 5645.9 5711.4 5712.4 5743.9 5843.7 5844.4 5868.9 5871.2 5873.8 4204.8 4209.5 4211.1 4221.0 4243.9 5577.2 5722.5 5725.9 5752.9 5756.0 5558.7 5588.6 5589.3 5594.0 5681.8 5401.0 5610.4 5628.9 5647.2 5691.9 5573.1 5578.2 5595.9 5597.5 5606.6 5653.8 5659.2 5662.5 5668.5 5669.1 5340.4 5507.6 5513.1 5522.4 5536.5 17:21:29 INFO - PROCESS | 5513 | 17:21:29 INFO - PROCESS | 5513 | [#5] yui.html Cycles:180 Average:17100.85 Median:46809.30 stddev:20891.59 (44.6%) stddev-sans-first:20914.21 17:21:29 INFO - PROCESS | 5513 | Values: 771.1 779.0 805.5 836.7 846.4 14396.2 14490.3 14594.0 15071.2 16019.5 13353.0 14593.2 14832.0 14857.3 14863.1 812.1 850.7 851.1 852.6 852.9 841.9 844.4 844.4 844.4 844.7 894.9 899.9 899.9 900.1 900.2 891.2 892.5 892.9 893.1 893.9 654.4 655.0 655.2 655.3 655.5 13161.6 13230.4 13421.0 14094.6 14103.4 14789.9 14980.5 15160.0 15548.5 15596.0 12325.7 12414.4 12455.1 12456.5 12475.4 3865.3 3928.6 3962.6 4022.1 4033.3 56777.7 58316.4 58467.4 62987.4 66209.0 12028.3 12160.0 12177.3 12195.1 12196.4 20837.7 20970.0 20981.6 21335.9 21720.2 8964.6 8993.7 9072.4 9182.8 9222.5 4735.2 4841.8 4925.2 5001.2 5036.4 3543.7 3655.5 3658.4 3658.9 3662.8 624.7 636.5 641.8 642.8 662.2 792.8 802.6 805.9 810.0 826.2 9880.4 9883.1 9955.3 10492.7 10507.9 672.4 749.8 756.7 788.3 808.6 4621.4 4987.3 4994.6 5021.4 5029.8 55474.7 75930.2 76160.6 79688.6 82560.6 48541.8 68187.0 70889.0 73052.3 73925.0 65116.3 66735.7 67012.3 67021.7 69048.3 9908.9 10420.8 10511.4 10584.4 10604.2 45573.3 46715.3 46903.3 46973.3 47079.5 37395.8 38059.4 55261.0 55799.0 56424.4 12587.0 12944.9 13024.4 13387.4 13508.1 14261.5 14656.0 14660.8 14875.8 15091.2 13291.6 13606.4 13652.2 14060.4 14706.9 13927.2 13938.2 14073.5 14089.9 14180.1 13513.5 13564.7 14038.4 14246.7 14565.6 11666.2 11783.5 11914.3 11917.8 12304.9 11903.7 11977.2 12147.3 12386.2 12424.0 17:21:29 INFO - PROCESS | 5513 | -------- Summary: end -------- 17:21:29 INFO - PROCESS | 5513 | 17:21:30 INFO - TEST-INFO | 5513: exit 0 17:21:30 INFO - TEST-OK | dromaeo_css | took 1114905ms 17:21:30 INFO - TEST-START | kraken 17:21:30 INFO - operating with platform_type : linux_ 17:21:30 INFO - Initialising browser for kraken test... 17:21:30 INFO - TEST-INFO | started process 5833 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UIuyU/profile http://localhost:57027/getInfo.html) 17:21:34 INFO - PROCESS | 5833 | __metrics Screen width/height:1600/1200 17:21:34 INFO - PROCESS | 5833 | colorDepth:24 17:21:34 INFO - PROCESS | 5833 | Browser inner width/height: 1024/697 17:21:34 INFO - PROCESS | 5833 | __metrics 17:21:34 INFO - PROCESS | 5833 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:34 INFO - PROCESS | 5833 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:35 INFO - PROCESS | 5833 | console.error: PushService: 17:21:35 INFO - PROCESS | 5833 | stateChangeProcessEnqueue: Error transitioning state 17:21:35 INFO - PROCESS | 5833 | UnknownError 17:21:35 INFO - TEST-INFO | 5833: exit 0 17:21:35 INFO - Browser initialized. 17:21:35 INFO - Running cycle 1/1 for kraken test... 17:21:35 INFO - TEST-INFO | started process 5929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UIuyU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:47 INFO - PROCESS | 5929 | RSS: Main: 143495168 17:21:47 INFO - PROCESS | 5929 | 17:21:50 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57027/tests/kraken/driver.html?testName=audio-beat-detection) 17:21:50 INFO - PROCESS | 5929 | RSS: Main: 185286656 17:21:50 INFO - PROCESS | 5929 | 17:21:53 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57027/tests/kraken/driver.html?testName=audio-dft) 17:21:54 INFO - PROCESS | 5929 | RSS: Main: 175222784 17:21:54 INFO - PROCESS | 5929 | 17:21:56 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57027/tests/kraken/driver.html?testName=audio-fft) 17:21:57 INFO - PROCESS | 5929 | RSS: Main: 266723328 17:21:57 INFO - PROCESS | 5929 | 17:21:59 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57027/tests/kraken/driver.html?testName=audio-oscillator) 17:22:00 INFO - PROCESS | 5929 | RSS: Main: 173334528 17:22:00 INFO - PROCESS | 5929 | 17:22:02 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57027/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:22:02 INFO - PROCESS | 5929 | RSS: Main: 175570944 17:22:02 INFO - PROCESS | 5929 | 17:22:05 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57027/tests/kraken/driver.html?testName=imaging-darkroom) 17:22:06 INFO - PROCESS | 5929 | RSS: Main: 214454272 17:22:06 INFO - PROCESS | 5929 | 17:22:09 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57027/tests/kraken/driver.html?testName=imaging-desaturate) 17:22:10 INFO - PROCESS | 5929 | RSS: Main: 212942848 17:22:10 INFO - PROCESS | 5929 | 17:22:12 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57027/tests/kraken/driver.html?testName=json-parse-financial) 17:22:13 INFO - PROCESS | 5929 | RSS: Main: 211562496 17:22:13 INFO - PROCESS | 5929 | 17:22:15 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57027/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:22:15 INFO - PROCESS | 5929 | RSS: Main: 173867008 17:22:15 INFO - PROCESS | 5929 | 17:22:17 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:22:17 INFO - PROCESS | 5929 | RSS: Main: 180682752 17:22:17 INFO - PROCESS | 5929 | 17:22:19 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:22:20 INFO - PROCESS | 5929 | RSS: Main: 189857792 17:22:20 INFO - PROCESS | 5929 | 17:22:23 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:22:23 INFO - PROCESS | 5929 | RSS: Main: 199053312 17:22:23 INFO - PROCESS | 5929 | 17:22:26 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:22:26 INFO - PROCESS | 5929 | RSS: Main: 194965504 17:22:26 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | Cycle 1(1): loaded http://localhost:57027/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57027/tests/kraken/driver.html?testName=ai-astar) 17:22:28 INFO - PROCESS | 5929 | RSS: Main: 246849536 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | __start_tp_report 17:22:28 INFO - PROCESS | 5929 | _x_x_mozilla_page_load 17:22:28 INFO - PROCESS | 5929 | _x_x_mozilla_page_load_details 17:22:28 INFO - PROCESS | 5929 | |i|pagename|runs| 17:22:28 INFO - PROCESS | 5929 | |0;ai-astar;103;112;113;112;112;111;114;111;112;113 17:22:28 INFO - PROCESS | 5929 | |1;audio-beat-detection;142;124;118;165;118;118;122;126;157;119 17:22:28 INFO - PROCESS | 5929 | |2;audio-dft;159;161;161;161;159;161;161;161;160;161 17:22:28 INFO - PROCESS | 5929 | |3;audio-fft;74;75;75;107;75;75;76;75;96;74 17:22:28 INFO - PROCESS | 5929 | |4;audio-oscillator;83;83;110;83;82;82;98;82;82;107 17:22:28 INFO - PROCESS | 5929 | |5;imaging-gaussian-blur;118;117;117;116;116;117;117;117;116;116 17:22:28 INFO - PROCESS | 5929 | |6;imaging-darkroom;147;146;149;146;147;146;148;146;146;146 17:22:28 INFO - PROCESS | 5929 | |7;imaging-desaturate;85;85;84;85;86;85;85;84;85;85 17:22:28 INFO - PROCESS | 5929 | |8;json-parse-financial;54;50;49;50;50;51;49;51;50;50 17:22:28 INFO - PROCESS | 5929 | |9;json-stringify-tinderbox;58;58;58;59;59;58;57;58;59;59 17:22:28 INFO - PROCESS | 5929 | |10;stanford-crypto-aes;75;83;74;75;78;75;74;74;74;74 17:22:28 INFO - PROCESS | 5929 | |11;stanford-crypto-ccm;124;122;124;124;122;119;129;124;171;128 17:22:28 INFO - PROCESS | 5929 | |12;stanford-crypto-pbkdf2;154;155;155;152;159;152;158;153;154;153 17:22:28 INFO - PROCESS | 5929 | |13;stanford-crypto-sha256-iterative;65;63;65;62;62;65;62;62;62;61 17:22:28 INFO - PROCESS | 5929 | __end_tp_report 17:22:28 INFO - PROCESS | 5929 | __start_cc_report 17:22:28 INFO - PROCESS | 5929 | _x_x_mozilla_cycle_collect,1584 17:22:28 INFO - PROCESS | 5929 | __end_cc_report 17:22:28 INFO - PROCESS | 5929 | __startTimestamp1484184148910__endTimestamp 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | ------- Summary: start ------- 17:22:28 INFO - PROCESS | 5929 | Number of tests: 14 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.06 (2.7%) stddev-sans-first:0.97 17:22:28 INFO - PROCESS | 5929 | Values: 103.0 112.0 113.0 112.0 112.0 111.0 114.0 111.0 112.0 113.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:125.00 stddev:17.51 (14.0%) stddev-sans-first:18.10 17:22:28 INFO - PROCESS | 5929 | Values: 142.0 124.0 118.0 165.0 118.0 118.0 122.0 126.0 157.0 119.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.85 (0.5%) stddev-sans-first:0.71 17:22:28 INFO - PROCESS | 5929 | Values: 159.0 161.0 161.0 161.0 159.0 161.0 161.0 161.0 160.0 161.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#3] audio-fft Cycles:10 Average:80.20 Median:75.00 stddev:11.54 (15.4%) stddev-sans-first:12.02 17:22:28 INFO - PROCESS | 5929 | Values: 74.0 75.0 75.0 107.0 75.0 75.0 76.0 75.0 96.0 74.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.50 stddev:11.30 (13.7%) stddev-sans-first:11.76 17:22:28 INFO - PROCESS | 5929 | Values: 83.0 83.0 110.0 83.0 82.0 82.0 98.0 82.0 82.0 107.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 17:22:28 INFO - PROCESS | 5929 | Values: 118.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 17:22:28 INFO - PROCESS | 5929 | Values: 147.0 146.0 149.0 146.0 147.0 146.0 148.0 146.0 146.0 146.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 17:22:28 INFO - PROCESS | 5929 | Values: 85.0 85.0 84.0 85.0 86.0 85.0 85.0 84.0 85.0 85.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:1.43 (2.9%) stddev-sans-first:0.71 17:22:28 INFO - PROCESS | 5929 | Values: 54.0 50.0 49.0 50.0 50.0 51.0 49.0 51.0 50.0 50.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 17:22:28 INFO - PROCESS | 5929 | Values: 58.0 58.0 58.0 59.0 59.0 58.0 57.0 58.0 59.0 59.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:75.00 stddev:2.88 (3.8%) stddev-sans-first:3.04 17:22:28 INFO - PROCESS | 5929 | Values: 75.0 83.0 74.0 75.0 78.0 75.0 74.0 74.0 74.0 74.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:124.00 stddev:15.14 (12.2%) stddev-sans-first:15.96 17:22:28 INFO - PROCESS | 5929 | Values: 124.0 122.0 124.0 124.0 122.0 119.0 129.0 124.0 171.0 128.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:154.50 stddev:2.37 (1.5%) stddev-sans-first:2.51 17:22:28 INFO - PROCESS | 5929 | Values: 154.0 155.0 155.0 152.0 159.0 152.0 158.0 153.0 154.0 153.0 17:22:28 INFO - PROCESS | 5929 | 17:22:28 INFO - PROCESS | 5929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:62.50 stddev:1.52 (2.4%) stddev-sans-first:1.41 17:22:28 INFO - PROCESS | 5929 | Values: 65.0 63.0 65.0 62.0 62.0 65.0 62.0 62.0 62.0 61.0 17:22:28 INFO - PROCESS | 5929 | -------- Summary: end -------- 17:22:28 INFO - PROCESS | 5929 | 17:22:29 INFO - TEST-INFO | 5929: exit 0 17:22:30 INFO - TEST-OK | kraken | took 59669ms 17:22:30 INFO - SUITE-END | took 1174s 17:22:30 INFO - Completed test suite (00:19:34) 17:22:30 INFO - javascript benchmark 17:22:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3180.2208458927566, 3187.9521807172896, 3200.8879689210867, 3223.838808059599, 3243.497257925021, 5472.835814925548, 5554.361248456429, 5569.303837020372, 5584.888302233953, 5607.579431542636, 5555.527780138693, 5626.071698169807, 5628.915566266509, 5631.662100273976, 5651.2935883014625, 5037.992401519692, 5066.518680725344, 5094.057599344114, 5144.8971020579565, 5183.067047931371, 4957.578605818491, 4990.351254336949, 5131.102057140004, 5179.8446046618665, 5182.455842136578, 5054.9241761373605, 5060.291559181717, 5063.088644044071, 5106.923396149061, 5137.229415587654, 5181.974090129563, 5194.68831870089, 5194.948050519484, 5198.194279886624, 5227.0, 4818.156822556039, 5078.64449488536, 5089.389273287229, 5120.411152717448, 5162.896742065138, 5378.704171270579, 5524.613277070606, 5551.861203469905, 5560.4161563035705, 5566.471185237437, 5976.432238937337, 6031.577789554733, 6085.6348619083, 6089.786857460012, 6104.664243466607, 5766.452187042224, 6123.020316749344, 6131.846703832395, 6132.6933653317155, 6140.785072522441, 5416.702081385522, 5563.805266815682, 5612.719364031782, 5620.662760234399, 5628.0, 6140.938590614126, 6144.293406258337, 6150.969245153746, 6165.229346331708, 6166.321704612489, 5646.096624540054, 5921.319048309413, 5937.168796368512, 5943.375945525658, 5948.910766338508, 5773.855653608609, 5999.130126131687, 6050.001749711337, 6058.818235452944, 6124.989376752786, 6084.056971169476, 6089.269287685417, 6100.298465676416, 6101.908471372933, 6130.111133885562, 6065.272167339859, 6099.9085013724825, 6106.786262480792, 6122.142899993915, 6130.233720784902, 4468.106378724203, 4501.504834468205, 4625.791839367236, 4663.020765639186, 4665.95334046662, 4143.81352839117, 4169.645580125723, 4180.038591123997, 4191.622753952159, 4208.410822484854, 5734.053881109654, 5736.827895163152, 5742.598018138774, 5744.425557444222, 5751.85620359486, 5770.826875193751, 5817.476427121494, 5823.708814559255, 5954.910676339859, 6097.7865774697675, 5630.662160270397, 5667.914981275202, 5671.0, 5681.857953551194, 5701.857453563694, 5207.427183009866, 5208.296879921238, 5211.296474975906, 5215.139501982207, 5237.6333656643665, 5797.739101740432, 5905.704714764331, 6080.300765412034, 6125.295591007091, 6138.386161383826, 6015.977283861842, 6088.208532890696, 6115.970584430425, 6116.633002019893, 6134.2332208473945, 6154.628180721825, 6169.629822210682, 6189.133521306931, 6190.597611155261, 6196.597221180609, 5601.6358936668985, 5614.9719251403485, 5952.315483719428, 5987.491063259672, 5988.191594134911, 5367.248585198154, 5922.022866227025, 5990.460858522754, 6069.544784141295, 6117.724702388314, 5885.735141918538, 5917.526597872074, 5920.703964801656, 5921.437463440966, 5929.881402372014, 5596.076647353141, 5902.61632993854, 5910.674912879833, 5914.34942156371, 5932.466078052996, 5702.37273899879, 5810.709464526842, 5842.38654941242, 5865.442782935615, 5889.0, 5991.490723288569, 5995.760169593342, 5996.70016499182, 5998.970005150121, 6001.36985616504, 5527.115661494142, 5575.721213939368, 5694.601377903507, 5840.445157710011, 5841.18223448726], "value": 5530.870310039803, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.6591444005256, 601.0383386581469, 625.121704005872, 653.5849735418008, 667.4393509452059, 34489.137771555696, 45435.77282113589, 46216.537834621646, 46273.30590041152, 49767.25349119757, 47010.0, 47412.762936185354, 47440.76279618597, 47464.525354746445, 47476.762616186956, 7986.281234688877, 8842.823143537142, 8987.955060224689, 8999.460032398045, 9001.144891235323, 8210.30212431944, 10458.52936617854, 10706.41114738689, 10707.143428525738, 11048.668539943814, 11013.779724405507, 11072.0, 11083.833742493869, 11085.27945683532, 11093.0, 10826.133909287239, 10863.67408977732, 10877.401742904138, 10881.673549793519, 10896.891031089706, 10756.73108172294, 10808.51361688726, 10817.78364432707, 10833.0, 10841.728956776065, 43338.78330608327, 43392.78303608462, 43446.78276608597, 43449.56550434487, 43798.12403751939, 3888.6889048876023, 3921.1765529238896, 3925.568187499373, 4103.876883693494, 4382.320740285261, 33029.50455743166, 33044.0, 33166.66833331684, 33365.33269334624, 33669.663303367146, 4538.205813982539, 4556.3621093047, 4598.779915532615, 4636.976815115937, 4701.929471057937, 346335.0, 346892.0, 348265.0, 348965.255173725, 349358.0, 62589.0, 63057.0, 63065.0, 63148.36851631517, 63151.684241579416, 39611.0, 41169.58830411657, 41196.58803411987, 65145.0, 65879.0, 18942.810571894104, 19038.428847134608, 19089.90455047716, 19146.52133696641, 19161.0, 13186.0, 13492.853107485973, 14249.145051296955, 14411.279436028157, 14527.418903243963, 20449.182032718523, 20717.68923466149, 20727.170913163303, 20755.0, 20759.377218683465, 7585.241475852371, 7639.961800190964, 7683.884741728879, 7763.844723105506, 7802.141764405911, 1052.6052730226165, 1058.222206678091, 1061.3472714280815, 1097.3251450357973, 1116.603605719974, 34047.82976085104, 34148.82925585356, 34324.0, 34636.13409664728, 34824.12939676478, 1122.8490796933208, 1170.7365842685329, 1215.5380955236953, 1239.429862263351, 1272.41468924294, 17433.47699569015, 17988.127835628686, 18003.917786152546, 18044.639107217783, 18127.546811329823, 540767.0, 543712.0, 547101.0, 547264.0, 549401.0, 58883.70558147182, 59416.0, 59443.0, 59504.70247648734, 59556.70221648864, 1289.6002239305844, 1356.647271709343, 1380.4892189889806, 1434.0535246737104, 1449.8695117439481, 32035.679643203268, 35020.64979350174, 35141.47287790736, 37146.62853371432, 37177.0, 56271.71864140653, 56770.99999999835, 56844.431555683914, 56875.43124568701, 56910.14634780565, 439530.0, 440876.0, 441196.0, 441890.0, 442217.0, 25847.741522584533, 30560.388792223588, 30774.84612576923, 30813.0, 32328.35343293168, 48448.757756210995, 48449.0, 48968.0, 49026.0, 49072.263916040574, 40612.79693601513, 41218.0, 41414.0, 42367.788161059, 42690.0, 41732.79133604313, 42235.36646950237, 42614.78692606641, 43251.48619798418, 43776.124477509635, 43148.56851431446, 43452.78273608612, 43518.564814352714, 43537.129257415305, 43571.0, 36729.63270367369, 38567.2154216965, 38793.67994490359, 38802.41796373113, 39414.80292598633, 29311.7068829309, 29676.554851677673, 29841.701582983893, 30077.0, 30320.696793031788], "value": 21414.983463203374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.65845665011364, 422.2736892544826, 429.59617959118435, 431.69565456353274, 446.58467625108636, 18562.443126706203, 18878.811211887878, 20175.192992280292, 20225.292114775984, 20311.898440507794, 19168.52078698033, 19346.709799353022, 19534.31629892954, 19614.901925490354, 19617.803821961777, 5586.162075688659, 5824.330202026778, 5830.09633506805, 5834.474897259246, 5840.415958404147, 5526.364468086182, 5642.294713160855, 5681.602287839853, 5719.999000174974, 5726.7422965966425, 5670.206171136024, 5679.7160141992945, 5685.886282274352, 5687.374388817227, 5701.572382071361, 5571.4428557144365, 5622.072358060915, 5671.319441667006, 5673.631213971079, 5676.829695109112, 5172.6379153459275, 5172.948270517284, 5174.767135478913, 5176.7411629418375, 5182.404023537304, 23779.0, 23841.88079059611, 23981.640275395883, 24062.879685601634, 24202.39494012646, 2519.5212909547126, 2575.25317657879, 2700.39241170737, 2890.7398334149816, 3228.4834426491648, 14938.178400187986, 15160.09039457636, 15230.543083707396, 15318.004329718533, 15445.459408920746, 1899.278274255774, 1975.8518111141723, 1983.880967141976, 1984.791596882321, 2022.6662600670873, 57559.0, 68142.0, 78624.60687696582, 83399.0, 83701.58149209277, 11122.721931951766, 11220.551177952953, 11416.31502109876, 11511.539538418538, 11611.954924056876, 16354.182290885408, 16358.182090895407, 16370.67258654844, 16403.179841007903, 16411.42560010394, 8613.267872230805, 8675.132486751274, 8693.782655433664, 8816.559172041501, 8967.865482017774, 8483.44857584255, 8496.065432802387, 8497.61760720769, 8684.131586841266, 8721.825563488821, 6924.3421874921805, 6934.930650693428, 7016.894746578805, 7025.716642091239, 7040.859182816315, 1960.1865225931078, 1975.8221760041665, 2054.856160068811, 2069.793020697918, 2089.3731880435807, 830.8546004449197, 950.933434659581, 970.7912798748303, 1001.8246806808779, 1046.2257929132388, 20045.298414555713, 20221.39335819928, 20481.38555843327, 20986.47533811667, 21092.261770838253, 1026.1329176845554, 1045.17953406577, 1045.8954104589632, 1110.1008183371496, 1122.315387613555, 11101.500432480558, 11663.475143618558, 11726.648200553997, 11766.411679415995, 11934.164608477497, 384107.0, 435396.0, 449878.0, 452097.0, 467708.0, 89361.0, 90120.54939725259, 90359.0, 90635.54682226678, 91222.0, 1038.1020417339098, 1040.2977989856815, 1064.744461329291, 1071.0253669161027, 1073.7691396349821, 17662.558436039202, 17724.734128988333, 18178.36375726843, 18227.0, 18282.817171828112, 41284.0, 42257.366139508544, 42388.576114238465, 42662.0, 43038.35442468303, 56458.153127702295, 57305.0, 57468.0, 57756.71121644365, 58231.0, 8653.61058752345, 8869.822603547764, 8901.154390332907, 9051.04963978799, 9051.27589792828, 13237.933810330887, 13460.46158153702, 13501.59495215145, 13928.0, 14039.929800350932, 11966.521339146337, 12180.939095304468, 12248.571300004458, 12319.568815091428, 12596.874031259571, 11671.59149429766, 11820.172587918763, 11948.342841143472, 11955.163138580216, 12042.879571204527, 10495.947520262655, 12045.33750643723, 12300.569480068154, 12315.384230788246, 12671.493140274655, 8392.32861371101, 8547.871781923152, 8603.397762156588, 8614.44006139599, 8935.061818509223, 11545.826812597987, 11555.2489088209, 11578.884211157781, 11591.362475063952, 11600.709982250512], "value": 9624.878467576835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.146056747654, 1529.0825504697182, 1676.6730487554923, 1806.9909650451746, 1810.5202121437812, 8497.787555311113, 9054.366194366397, 9055.637774489012, 9057.320700947423, 9069.818603627931, 7100.289971002897, 7283.599402032886, 7295.161056478507, 7462.962685186567, 8931.285497160228, 2429.6112621980487, 2529.5067461844856, 2537.0486067724605, 2538.2892790018823, 2548.1336345642476, 2500.3374105861963, 2511.522810665977, 2544.0332673584103, 2565.1663209456906, 2565.730598287181, 2517.987410062947, 2520.8865601047996, 2544.3384719972846, 2546.796256299501, 2550.1584477122597, 2470.987645061772, 2488.5147396257735, 2488.912888048919, 2490.9128180513685, 2493.7256901740793, 2465.790407815338, 2509.3224829295987, 2511.849289042645, 2512.0454227393657, 2512.4095837478176, 8534.829303413959, 8616.224539791448, 8619.784505387353, 8632.482051076955, 8634.438761480484, 5488.313960754906, 5624.5781566382275, 5632.633878797865, 5633.8309850704545, 5639.351474580435, 3313.337332533503, 3380.188754698879, 3487.093355727504, 3520.6479352064844, 3525.6121826599056, 1122.3490375582144, 1130.7681925205313, 1132.6610549215843, 1132.971675708114, 1183.089021453476, 12078.335691536962, 12292.262464252173, 12321.5071397144, 12333.50665973369, 12361.75276494474, 4776.8566942991765, 5018.3476148101245, 5103.0, 5111.258867464197, 5135.101357262464, 10562.419066951237, 10569.524371403306, 10584.259101862795, 10584.629537966135, 10598.04617584421, 5698.914516282259, 5700.173474846124, 5703.17303990919, 5704.828855134353, 5707.8858422832145, 4308.80610372534, 5017.147084995559, 5061.34202553673, 5098.694078355311, 5126.55374508925, 3819.236152769457, 4018.959810401917, 4156.605122513356, 4159.022629682022, 4204.0540878302745, 1387.668716556175, 1435.2321507993174, 1437.4681367893945, 1444.3572610188576, 1462.4442711769673, 912.9680461183826, 948.4309414351334, 949.9382540134869, 954.3367359685091, 1061.0185578340004, 5923.822285331447, 5956.761729530857, 6110.877782444326, 6177.84555386119, 6213.378662133841, 985.1478471122571, 1003.598560575761, 1034.384541197986, 1035.53400969564, 1059.7456610413444, 4253.149370125987, 4457.465104187519, 4475.7985890635, 4491.708038977456, 4506.639468842484, 10576.682699519026, 10801.027907488364, 10874.72813179661, 10907.236493445384, 10924.071453926505, 10345.37927724339, 10840.241183117107, 10847.51186196623, 10862.61980830683, 10871.0, 8223.753287401387, 8412.62143203545, 8414.915850841535, 8414.957925210334, 8419.663213471393, 5989.131575921379, 6073.30157031947, 6180.81457556274, 6254.249490061222, 6278.0, 9800.558974846292, 9807.215422766305, 9897.554610042422, 9938.304318697621, 9950.701478955643, 9867.555959981675, 9934.503274836085, 9987.800243995225, 10385.948070259903, 10396.0, 6574.737010519526, 7968.920310796818, 8125.349972002344, 8141.592920354077, 8144.633491492778, 7227.457940654577, 8230.259276665129, 8384.790380240298, 8460.323174145931, 8584.527850968258, 7399.704011839466, 7407.66665500043, 7461.067366579177, 7596.164421913474, 7631.69473221065, 7006.824829379102, 7116.9644151780985, 7188.820279492846, 7228.530145540523, 7525.510841795266, 7115.252898445796, 7154.892676609959, 7161.892571611325, 7170.282971702788, 7173.784786456415, 5481.342238931354, 5557.72211389437, 5565.554755619622, 5626.0, 5708.058170401837, 5309.36287645485, 5451.400345961862, 5627.071533196977, 5633.577481688972, 5647.943520564906], "value": 4777.593542016884, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.51779356765155, 365.1145971020275, 366.421054733521, 369.09571549703236, 371.0667670807917, 5334.5999050071205, 5402.405735369106, 5417.889332686799, 5430.429804870491, 5438.673679579228, 5264.229357934832, 5323.976888782267, 5361.839144825652, 5369.650972686773, 5385.21914322423, 4209.810558524874, 4215.51521575019, 4223.556526564713, 4253.787310634471, 4273.764942928137, 4233.089885674579, 4241.36379543068, 4248.874048377183, 4269.679774016946, 4297.419848320484, 4249.1714115747445, 4256.531781504032, 4258.212230737308, 4271.273883439807, 4274.658027357804, 4146.310041720758, 4258.403823464718, 4259.403683484315, 4260.275753121977, 4307.504636966742, 3783.754055986352, 3788.71584631151, 3796.9620303796883, 3798.4682144499516, 3801.4107813288983, 5199.4401679496, 5211.530962213381, 5226.634135610509, 5232.738363081869, 5258.291055407008, 4730.741859550805, 4804.159272127364, 4845.588125009378, 4873.463918968911, 4897.706137631718, 5519.889602207973, 5562.0, 5570.777168913239, 5596.748146333384, 5654.208410822447, 4227.3447615619325, 4235.597618226295, 4239.533651298386, 4262.893427664333, 4271.1671224111315, 5600.047991841397, 5822.912656310159, 5855.3266374367495, 5945.524358051389, 5952.3154837193415, 4237.639800616982, 4240.257954857888, 4260.297050986553, 4260.424842646203, 4263.211305908417, 4296.656267498593, 4301.849435269751, 4304.246756817607, 4309.59058889405, 4317.266064768997, 4705.341252224691, 4743.430788305425, 4756.0, 4905.117078925828, 4911.656184067081, 5571.161516699561, 5612.382637909826, 5624.887502249932, 5630.127330263816, 5656.575756818255, 1410.7460657081824, 1420.2259768426231, 1422.5945605502588, 1422.8079209306818, 1430.6351880270672, 943.8065196634674, 990.9454979976026, 996.3722854601555, 1003.7641154328727, 1006.2302338710892, 5186.377634683787, 5196.662216955886, 5215.400228973641, 5228.581713462914, 5235.042201156334, 5488.874780669953, 5514.503694667499, 5529.0924631002235, 5541.196526503634, 5545.639533430395, 4960.404751429852, 5162.889978654607, 5220.903610241816, 5224.92162617561, 5243.632945693841, 2948.764098872085, 2959.5708622249654, 2964.570137330075, 2972.271793410585, 2983.418233444501, 5669.262995810601, 5674.347450043216, 5682.920245153407, 5697.857553561194, 5707.34365547959, 5385.138377859524, 5613.859653508695, 5617.522510586646, 5661.547076233891, 5669.858253543612, 5708.602386997471, 5760.481556659921, 5903.0260007099205, 5916.319623243296, 5919.704014799329, 5709.857253568694, 5720.885582288414, 5738.8278351649515, 5738.971305143447, 5761.539076873756, 5539.639923405127, 5645.943540564542, 5711.400302968129, 5712.37163911961, 5743.8564035899435, 5843.678597677169, 5844.3571207168025, 5868.882622347614, 5871.2073870027, 5873.765049397976, 4204.831806727697, 4209.515905670887, 4211.136716973075, 4221.0, 4243.893902652459, 5577.247071645276, 5722.484976352148, 5725.8568535786935, 5752.856178595402, 5755.971220143872, 5558.6648011192965, 5588.552915766811, 5589.273394458694, 5593.965116453469, 5681.772729090955, 5401.0, 5610.382857885547, 5628.8874222514505, 5647.20939068539, 5691.8861622768145, 5573.080441727069, 5578.246936663661, 5595.916061259166, 5597.496225339739, 5606.635568688022, 5653.802116925888, 5659.179418984307, 5662.480196312766, 5668.51817595509, 5669.121286200645, 5340.385855626652, 5507.586930980273, 5513.050517973025, 5522.420145884625, 5536.501714845684], "value": 4302.1904777609325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.090113665874, 779.0301075161425, 805.5086397297647, 836.6695155413612, 846.3609974469275, 14396.208208548525, 14490.275486225699, 14594.0, 15071.246437678114, 16019.519414417571, 13353.0, 14593.197374144444, 14832.0, 14857.257137143153, 14863.108213507197, 812.1107387410791, 850.698002209213, 851.093585331623, 852.5694524265238, 852.9189726975958, 841.8947631546057, 844.3582877013483, 844.3962566764762, 844.4257904624852, 844.666356789068, 894.8881389826272, 899.874017637528, 899.874017637528, 900.0909081827336, 900.1853322742895, 891.1667590802621, 892.5358813416987, 892.8616064510044, 893.1113542025694, 893.9329550283754, 654.4241067860253, 655.0338251079619, 655.2137435077877, 655.2922843329193, 655.4919937048825, 13161.605151845364, 13230.404631791593, 13421.0, 14094.647633809134, 14103.435862565486, 14789.926050369786, 14980.475683351031, 15160.0, 15548.455804046804, 15596.0, 12325.69185770354, 12414.379281035912, 12455.128141030042, 12456.501739930392, 12475.438605262785, 3865.2849222893574, 3928.64642182205, 3962.643362097425, 4022.1352409231863, 4033.294173519652, 56777.71611141959, 58316.416835831944, 58467.415325846625, 62987.37012629861, 66209.0, 12028.338441385808, 12160.0, 12177.269363838199, 12195.146339756131, 12196.39010621697, 20837.68743468849, 20970.0, 20981.580368392853, 21335.893320533607, 21720.23979160722, 8964.551772411354, 8993.685221017233, 9072.364934454525, 9182.816343673088, 9222.492762897971, 4735.171345014609, 4841.806327746851, 4925.1873440881855, 5001.249812528077, 5036.395632524047, 3543.698785603201, 3655.4882316475714, 3658.414653655402, 3658.8719394821055, 3662.7619204751604, 624.6970219443566, 636.4812677667686, 641.8010416770816, 642.7979678002166, 662.1656712542232, 792.7780221537863, 802.6348011654683, 805.9073206581318, 809.9757007289791, 826.182079741052, 9880.35777674449, 9883.110520053086, 9955.30312878106, 10492.737681558023, 10507.94746026265, 672.4183581202225, 749.8050506868144, 756.7313603670727, 788.3444765991372, 808.6078252047752, 4621.353010578522, 4987.326710893983, 4994.575461085776, 5021.372328458942, 5029.84910452687, 55474.72262638661, 75930.24069759232, 76160.61919690476, 79688.601556993, 82560.58719706364, 48541.757291214024, 68187.0, 70889.0, 73052.26947730561, 73925.0, 65116.34883651198, 66735.66632166905, 67012.3298767006, 67021.6648916762, 69048.3095169052, 9908.851367229641, 10420.791584168426, 10511.369317840954, 10584.364938103738, 10604.204684648528, 45573.31640025336, 46715.29927051165, 46903.29645055395, 46973.295400568335, 47079.52920470751, 37395.81302093472, 38059.42910856284, 55261.0, 55799.0, 56424.43575564192, 12587.0, 12944.93527532394, 13024.413901374272, 13387.397567109309, 13508.121972071784, 14261.500847470288, 14656.0, 14660.78008829847, 14875.776863347275, 15091.169985650895, 13291.601251962456, 13606.387712553156, 13652.24912629815, 14060.367283472473, 14706.926465367605, 13927.234002129575, 13938.163710177421, 14073.507427239998, 14089.859101408854, 14180.14919104857, 13513.527026554024, 13564.660883477993, 14038.438462461387, 14246.715065698421, 14565.635859103608, 11666.183367164216, 11783.52865885355, 11914.285142891107, 11917.761644766882, 12304.876951230373, 11903.702407439538, 11977.221480604081, 12147.336596171319, 12386.194897331645, 12424.0], "value": 7401.597952405333, "unit": "score"}], "name": "dromaeo_css", "value": 7467.85798905672, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 113.0, 112.0, 112.0, 111.0, 114.0, 111.0, 112.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 124.0, 118.0, 165.0, 118.0, 118.0, 122.0, 126.0, 157.0, 119.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 161.0, 159.0, 161.0, 161.0, 161.0, 160.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 107.0, 75.0, 75.0, 76.0, 75.0, 96.0, 74.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 83.0, 82.0, 82.0, 98.0, 82.0, 82.0, 107.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 149.0, 146.0, 147.0, 146.0, 148.0, 146.0, 146.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 86.0, 85.0, 85.0, 84.0, 85.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 50.0, 49.0, 50.0, 50.0, 51.0, 49.0, 51.0, 50.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 59.0, 58.0, 57.0, 58.0, 59.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 74.0, 75.0, 78.0, 75.0, 74.0, 74.0, 74.0, 74.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 124.0, 124.0, 122.0, 119.0, 129.0, 124.0, 171.0, 128.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 155.0, 152.0, 159.0, 152.0, 158.0, 153.0, 154.0, 153.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 62.0, 62.0, 65.0, 62.0, 62.0, 62.0, 61.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1450.8, "alertThreshold": 2.0}]} 17:22:30 INFO - Return code: 0 17:22:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:22:30 INFO - # TBPL SUCCESS # 17:22:30 INFO - Running post-action listener: _resource_record_post_action 17:22:30 INFO - [mozharness: 2017-01-12 01:22:30.218878Z] Finished run-tests step (success) 17:22:30 INFO - Running post-run listener: _resource_record_post_run 17:22:30 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 357564416; Read time: 28; Write time: 203664 17:22:30 INFO - TinderboxPrint: CPU usage
13.0% 17:22:30 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 17:22:30 INFO - TinderboxPrint: I/O write bytes / time
357,564,416 / 203,664 17:22:30 INFO - TinderboxPrint: CPU idle
8,271.7 (87.0%) 17:22:30 INFO - TinderboxPrint: CPU user
1,189.1 (12.5%) 17:22:30 INFO - TinderboxPrint: Swap in / out
0 / 0 17:22:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108380160; Read time: 0; Write time: 73736 17:22:30 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249184256; Read time: 16; Write time: 129928 17:22:30 INFO - Running post-run listener: _upload_blobber_files 17:22:30 INFO - Blob upload gear active. 17:22:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:22:30 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 17:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:22:31 INFO - (blobuploader) - INFO - Open directory for files ... 17:22:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:22:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:22:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:32 INFO - (blobuploader) - INFO - Done attempting. 17:22:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:22:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:22:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:35 INFO - (blobuploader) - INFO - Done attempting. 17:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:36 INFO - (blobuploader) - INFO - Done attempting. 17:22:36 INFO - (blobuploader) - INFO - Iteration through files over. 17:22:36 INFO - Return code: 0 17:22:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:22:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:22:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ed654861c51f643f119f8a31c539e7a14fca75cef2e3f636a6739b8c4b8c12b1d0c552b2a64e2880f0b68ec0a4917d38cd5fe8391128cfa2b41ca11e97e06b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0fc42758e139d8a7ef1305df5fb861bb3c9d10533221c21ac66bfc1aa42f6a85b9a49f016399cec8095228b7115f6f314db8ca56dfe64b221b30f3c0be9570c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d4ada5800af7ffb59a9d736878d6d1f55493048bed14b1f567c118360cc4c8688ab47311bbc6aa766ddfe25fd36d6023c5cbd77d2075810862a9a56a4c3bec6"} 17:22:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:22:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:22:36 INFO - Contents: 17:22:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ed654861c51f643f119f8a31c539e7a14fca75cef2e3f636a6739b8c4b8c12b1d0c552b2a64e2880f0b68ec0a4917d38cd5fe8391128cfa2b41ca11e97e06b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0fc42758e139d8a7ef1305df5fb861bb3c9d10533221c21ac66bfc1aa42f6a85b9a49f016399cec8095228b7115f6f314db8ca56dfe64b221b30f3c0be9570c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d4ada5800af7ffb59a9d736878d6d1f55493048bed14b1f567c118360cc4c8688ab47311bbc6aa766ddfe25fd36d6023c5cbd77d2075810862a9a56a4c3bec6"} 17:22:36 INFO - Running post-run listener: copy_logs_to_upload_dir 17:22:36 INFO - Copying logs to upload dir... 17:22:36 INFO - mkdir: /builds/slave/test/build/upload/logs 17:22:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.647333 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-01-11 17:22:36.192196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 17:22:36.195525) ========= 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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ed654861c51f643f119f8a31c539e7a14fca75cef2e3f636a6739b8c4b8c12b1d0c552b2a64e2880f0b68ec0a4917d38cd5fe8391128cfa2b41ca11e97e06b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0fc42758e139d8a7ef1305df5fb861bb3c9d10533221c21ac66bfc1aa42f6a85b9a49f016399cec8095228b7115f6f314db8ca56dfe64b221b30f3c0be9570c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d4ada5800af7ffb59a9d736878d6d1f55493048bed14b1f567c118360cc4c8688ab47311bbc6aa766ddfe25fd36d6023c5cbd77d2075810862a9a56a4c3bec6"} build_url:https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011844 build_url: 'https://queue.taskcluster.net/v1/task/WZkBSQE4R2CDD0U-WZNtew/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ed654861c51f643f119f8a31c539e7a14fca75cef2e3f636a6739b8c4b8c12b1d0c552b2a64e2880f0b68ec0a4917d38cd5fe8391128cfa2b41ca11e97e06b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0fc42758e139d8a7ef1305df5fb861bb3c9d10533221c21ac66bfc1aa42f6a85b9a49f016399cec8095228b7115f6f314db8ca56dfe64b221b30f3c0be9570c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d4ada5800af7ffb59a9d736878d6d1f55493048bed14b1f567c118360cc4c8688ab47311bbc6aa766ddfe25fd36d6023c5cbd77d2075810862a9a56a4c3bec6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 17:22:36.221212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:22:36.221531) ========= 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=232498196343f89f466a4a9a000001c8-1484182248.742550-1600075407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 17:22:36.306139) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 17:22:36.306464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 17:22:36.306747) ========= ========= Total master_lag: 0.15 =========