builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1477865541.69 results: success (0) buildid: 20161030132336 builduid: 77e55c802b1e4e6c83180ce4c98f6e07 revision: bed4b65bc2361fde5a43f12131e88b1883a00be5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.693510) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.693955) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.694223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.712617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.712966) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-30 15:12:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.7M=0s 2016-10-30 15:12:22 (45.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121110 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.847796) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.848135) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022908 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:21.902085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-30 15:12:21.905502) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev bed4b65bc2361fde5a43f12131e88b1883a00be5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev bed4b65bc2361fde5a43f12131e88b1883a00be5 --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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-30 15:12:22,160 truncating revision to first 12 chars 2016-10-30 15:12:22,160 Setting DEBUG logging. 2016-10-30 15:12:22,160 attempt 1/10 2016-10-30 15:12:22,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/bed4b65bc236?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-30 15:12:23,309 unpacking tar archive at: mozilla-inbound-bed4b65bc236/testing/mozharness/ program finished with exit code 0 elapsedTime=1.475115 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-30 15:12:23.394065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:23.397453) ========= script_repo_revision: bed4b65bc2361fde5a43f12131e88b1883a00be5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:23.397866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:23.398124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 15:12:23.409227) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-10-30 15:12:23.409610) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:12:23 INFO - MultiFileLogger online at 20161030 15:12:23 in /builds/slave/test 15:12:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:12:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:12:23 INFO - {'append_to_log': False, 15:12:23 INFO - 'base_work_dir': '/builds/slave/test', 15:12:23 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:12:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:12:23 INFO - 'branch': 'Mozilla-Inbound', 15:12:23 INFO - 'buildbot_json_path': 'buildprops.json', 15:12:23 INFO - 'config_files': ('talos/linux_config.py',), 15:12:23 INFO - 'default_actions': ('clobber', 15:12:23 INFO - 'read-buildbot-config', 15:12:23 INFO - 'download-and-extract', 15:12:23 INFO - 'populate-webroot', 15:12:23 INFO - 'create-virtualenv', 15:12:23 INFO - 'install', 15:12:23 INFO - 'run-tests'), 15:12:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:12:23 INFO - 'download_minidump_stackwalk': True, 15:12:23 INFO - 'download_symbols': 'ondemand', 15:12:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:12:23 INFO - 'tooltool.py': '/tools/tooltool.py', 15:12:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:12:23 INFO - '/tools/misc-python/virtualenv.py')}, 15:12:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:12:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:12:23 INFO - 'installer_path': 'installer.exe', 15:12:23 INFO - 'log_level': 'info', 15:12:23 INFO - 'log_name': 'talos', 15:12:23 INFO - 'log_to_console': True, 15:12:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:12:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:12:23 INFO - 'opt_config_files': (), 15:12:23 INFO - 'pip_index': False, 15:12:23 INFO - 'sps_profile': False, 15:12:23 INFO - 'sps_profile_interval': 0, 15:12:23 INFO - 'suite': 'dromaeojs', 15:12:23 INFO - 'system_bits': '32', 15:12:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:12:23 INFO - 'title': 'talos-linux64-ix-067', 15:12:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:12:23 INFO - 'use_talos_json': True, 15:12:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:12:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:12:23 INFO - 'work_dir': 'build'} 15:12:23 INFO - [mozharness: 2016-10-30 22:12:23.762385Z] Running clobber step. 15:12:23 INFO - Running pre-action listener: _resource_record_pre_action 15:12:23 INFO - Running main action method: clobber 15:12:23 INFO - rmtree: /builds/slave/test/build 15:12:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:12:24 INFO - Running post-action listener: _resource_record_post_action 15:12:24 INFO - [mozharness: 2016-10-30 22:12:24.221391Z] Finished clobber step (success) 15:12:24 INFO - [mozharness: 2016-10-30 22:12:24.221490Z] Running read-buildbot-config step. 15:12:24 INFO - Running pre-action listener: _resource_record_pre_action 15:12:24 INFO - Running main action method: read_buildbot_config 15:12:24 INFO - Using buildbot properties: 15:12:24 INFO - { 15:12:24 INFO - "project": "", 15:12:24 INFO - "product": "firefox", 15:12:24 INFO - "script_repo_revision": "production", 15:12:24 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:12:24 INFO - "repository": "", 15:12:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:12:24 INFO - "buildid": "20161030132336", 15:12:24 INFO - "stage_platform": "linux64-pgo", 15:12:24 INFO - "basedir": "/builds/slave/test", 15:12:24 INFO - "buildnumber": 2694, 15:12:24 INFO - "platform": "ubuntu64_hw", 15:12:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:12:24 INFO - "slavebuilddir": "test", 15:12:24 INFO - "branch": "mozilla-inbound", 15:12:24 INFO - "revision": "bed4b65bc2361fde5a43f12131e88b1883a00be5", 15:12:24 INFO - "slavename": "talos-linux64-ix-067", 15:12:24 INFO - "builduid": "77e55c802b1e4e6c83180ce4c98f6e07", 15:12:24 INFO - "repo_path": "integration/mozilla-inbound" 15:12:24 INFO - } 15:12:24 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 15:12:24 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 15:12:24 INFO - Running post-action listener: _resource_record_post_action 15:12:24 INFO - [mozharness: 2016-10-30 22:12:24.223860Z] Finished read-buildbot-config step (success) 15:12:24 INFO - [mozharness: 2016-10-30 22:12:24.223948Z] Running download-and-extract step. 15:12:24 INFO - Running pre-action listener: _resource_record_pre_action 15:12:24 INFO - Running main action method: download_and_extract 15:12:24 INFO - mkdir: /builds/slave/test/build/tests 15:12: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')]} 15:12:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:12:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:12:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:12:24 INFO - Downloaded 1361 bytes. 15:12:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:12:24 INFO - Using the following test package requirements: 15:12:24 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:12:24 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:12:24 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:12:24 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'jsshell-linux-x86_64.zip'], 15:12:24 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:12:24 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:12:24 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:12:24 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:12:24 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:12:24 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:12:24 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:12:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:12:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:12:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:12:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:12:24 INFO - Http code: 200 15:12:24 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 15:12:24 INFO - x-amz-cf-id: U4s-oAKuRjMwXajvvQ51_nb9pjeK3hrMisb6ZKCDiZU0vrZvxdnVGw== 15:12:24 INFO - x-amz-replication-status: COMPLETED 15:12:24 INFO - x-amz-version-id: nJaxziqCEl.Q0TwSJ5t0tMuCL3beBUh6 15:12:24 INFO - Expected file size: 22006344 15:12:24 INFO - Obtained file size: 22006344 15:12:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:12:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:12:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:12:28 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:12:28 INFO - Http code: 200 15:12:28 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 15:12:28 INFO - x-amz-cf-id: zQXld9Xp7lBRvlEEqiaXEgaK1FXl_PKMK9IVWR3WJa8khKJCDexYdA== 15:12:28 INFO - x-amz-replication-status: COMPLETED 15:12:28 INFO - x-amz-version-id: nJaxziqCEl.Q0TwSJ5t0tMuCL3beBUh6 15:12:29 INFO - Expected file size: 22006344 15:12:29 INFO - Obtained file size: 22006344 15:12:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 15:12:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:12:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:12:33 INFO - Http code: 200 15:12:33 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 15:12:33 INFO - x-amz-cf-id: OO-jR-d-oJ264nPZx1XGutLwpC6mQfAN27AtdQXYurOfvz_yKAcewQ== 15:12:33 INFO - x-amz-replication-status: COMPLETED 15:12:33 INFO - x-amz-version-id: e2cu6ZEuefYrJahOEx_fmoa6yOCdrebq 15:12:33 INFO - Expected file size: 14409038 15:12:33 INFO - Obtained file size: 14409038 15:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:33 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:12:33 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:12:34 INFO - Downloaded 59646584 bytes. 15:12:34 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:12:34 INFO - mkdir: /builds/slave/test/properties 15:12:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:12:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:12:34 INFO - Contents: 15:12:34 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:12:35 INFO - Running post-action listener: _resource_record_post_action 15:12:35 INFO - Running post-action listener: set_extra_try_arguments 15:12:35 INFO - [mozharness: 2016-10-30 22:12:35.006623Z] Finished download-and-extract step (success) 15:12:35 INFO - [mozharness: 2016-10-30 22:12:35.006847Z] Running populate-webroot step. 15:12:35 INFO - Running pre-action listener: _resource_record_pre_action 15:12:35 INFO - Running main action method: populate_webroot 15:12:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:12:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:12:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:12:35 INFO - '--tppagecycles', 15:12:35 INFO - '7'], 15:12:35 INFO - 'tests': ['tsvgm']}}, 15:12:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:12:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:12:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:12:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:12:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:12:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:12:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['damp', 'tps']}, 15:12:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'tests': ['damp', 'tps']}, 15:12:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['dromaeo_dom']}, 15:12:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:12:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['basic_compositor_video']}, 15:12:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:12:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'other-e10s': {'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['a11yr', 15:12:35 INFO - 'ts_paint', 15:12:35 INFO - 'tpaint', 15:12:35 INFO - 'sessionrestore', 15:12:35 INFO - 'sessionrestore_no_auto_restore', 15:12:35 INFO - 'tabpaint']}, 15:12:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['tsvgx', 15:12:35 INFO - 'tsvgr_opacity', 15:12:35 INFO - 'tart', 15:12:35 INFO - 'tscrollx', 15:12:35 INFO - 'cart']}, 15:12:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:12:35 INFO - 'tsvgr_opacity', 15:12:35 INFO - 'tart', 15:12:35 INFO - 'tscrollx', 15:12:35 INFO - 'cart']}, 15:12:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'talos_options': ['--disable-e10s'], 15:12:35 INFO - 'tests': ['tp5o']}, 15:12:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'tests': ['tp5o']}, 15:12:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'talos_options': ['--disable-e10s', 15:12:35 INFO - '--xperf_path', 15:12:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:12:35 INFO - 'tests': ['tp5n']}, 15:12:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:35 INFO - 'talos_options': ['--xperf_path', 15:12:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:12:35 INFO - 'tests': ['tp5n']}}, 15:12:35 INFO - 'talos.zip': {'path': '', 15:12:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:12:35 INFO - Running post-action listener: _resource_record_post_action 15:12:35 INFO - [mozharness: 2016-10-30 22:12:35.025727Z] Finished populate-webroot step (success) 15:12:35 INFO - [mozharness: 2016-10-30 22:12:35.025814Z] Running create-virtualenv step. 15:12:35 INFO - Running pre-action listener: _resource_record_pre_action 15:12:35 INFO - Running main action method: create_virtualenv 15:12:35 INFO - Creating virtualenv /builds/slave/test/build/venv 15:12:35 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 15:12:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:12:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:12:35 INFO - Using real prefix '/usr' 15:12:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:12:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:12:37 INFO - Installing pip.................done. 15:12:37 INFO - Return code: 0 15:12:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:12:37 INFO - Reading from file tmpfile_stdout 15:12:37 INFO - Output received: 15:12:37 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) 15:12:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:12:37 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')]} 15:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:37 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')]} 15:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:37 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:37 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 15:12:37 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 15:12:37 INFO - Using env: {'DISPLAY': ':0', 15:12:37 INFO - 'HOME': '/home/cltbld', 15:12:37 INFO - 'LANG': 'en_US.UTF-8', 15:12:37 INFO - 'LANGUAGE': 'en_US:en', 15:12:37 INFO - 'LOGNAME': 'cltbld', 15:12:37 INFO - 'MAIL': '/var/mail/cltbld', 15:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:37 INFO - 'MOZ_NO_REMOTE': '1', 15:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:37 INFO - 'NO_EM_RESTART': '1', 15:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:37 INFO - 'PWD': '/builds/slave/test', 15:12:37 INFO - 'SHELL': '/bin/bash', 15:12:37 INFO - 'SHLVL': '1', 15:12:37 INFO - 'TERM': 'linux', 15:12:37 INFO - 'TMOUT': '86400', 15:12:37 INFO - 'USER': 'cltbld', 15:12:37 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:37 INFO - '_': '/tools/buildbot/bin/python'} 15:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:38 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 15:12:38 INFO - Cleaning up... 15:12:38 INFO - Return code: 0 15:12:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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')]} 15:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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', '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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:12: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 psutil>=3.1.1 15:12:38 INFO - Using env: {'DISPLAY': ':0', 15:12:38 INFO - 'HOME': '/home/cltbld', 15:12:38 INFO - 'LANG': 'en_US.UTF-8', 15:12:38 INFO - 'LANGUAGE': 'en_US:en', 15:12:38 INFO - 'LOGNAME': 'cltbld', 15:12:38 INFO - 'MAIL': '/var/mail/cltbld', 15:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:38 INFO - 'MOZ_NO_REMOTE': '1', 15:12:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:38 INFO - 'NO_EM_RESTART': '1', 15:12:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:38 INFO - 'PWD': '/builds/slave/test', 15:12:38 INFO - 'SHELL': '/bin/bash', 15:12:38 INFO - 'SHLVL': '1', 15:12:38 INFO - 'TERM': 'linux', 15:12:38 INFO - 'TMOUT': '86400', 15:12:38 INFO - 'USER': 'cltbld', 15:12:38 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:38 INFO - '_': '/tools/buildbot/bin/python'} 15:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:38 INFO - Downloading/unpacking psutil>=3.1.1 15:12:38 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 15:12:38 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 15:12:38 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 15:12:38 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 15:12:38 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 15:12:38 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 15:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:12:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:40 INFO - Installing collected packages: psutil 15:12:40 INFO - Running setup.py install for psutil 15:12:40 INFO - building 'psutil._psutil_linux' extension 15:12:40 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 15:12:40 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 15:12:40 INFO - building 'psutil._psutil_posix' extension 15:12:40 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 15:12:40 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 15:12:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:40 INFO - Successfully installed psutil 15:12:40 INFO - Cleaning up... 15:12:40 INFO - Return code: 0 15:12:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:12:40 INFO - Using env: {'DISPLAY': ':0', 15:12:40 INFO - 'HOME': '/home/cltbld', 15:12:40 INFO - 'LANG': 'en_US.UTF-8', 15:12:40 INFO - 'LANGUAGE': 'en_US:en', 15:12:40 INFO - 'LOGNAME': 'cltbld', 15:12:40 INFO - 'MAIL': '/var/mail/cltbld', 15:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:40 INFO - 'MOZ_NO_REMOTE': '1', 15:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:40 INFO - 'NO_EM_RESTART': '1', 15:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:40 INFO - 'PWD': '/builds/slave/test', 15:12:40 INFO - 'SHELL': '/bin/bash', 15:12:40 INFO - 'SHLVL': '1', 15:12:40 INFO - 'TERM': 'linux', 15:12:40 INFO - 'TMOUT': '86400', 15:12:40 INFO - 'USER': 'cltbld', 15:12:40 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:40 INFO - '_': '/tools/buildbot/bin/python'} 15:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:12:41 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 15:12:41 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 15:12:41 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 15:12:41 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 15:12:41 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 15:12:41 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 15:12:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:12:43 INFO - Installing collected packages: mozsystemmonitor 15:12:43 INFO - Running setup.py install for mozsystemmonitor 15:12:43 INFO - Successfully installed mozsystemmonitor 15:12:43 INFO - Cleaning up... 15:12:43 INFO - Return code: 0 15:12:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:12:43 INFO - Using env: {'DISPLAY': ':0', 15:12:43 INFO - 'HOME': '/home/cltbld', 15:12:43 INFO - 'LANG': 'en_US.UTF-8', 15:12:43 INFO - 'LANGUAGE': 'en_US:en', 15:12:43 INFO - 'LOGNAME': 'cltbld', 15:12:43 INFO - 'MAIL': '/var/mail/cltbld', 15:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:43 INFO - 'MOZ_NO_REMOTE': '1', 15:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:43 INFO - 'NO_EM_RESTART': '1', 15:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:43 INFO - 'PWD': '/builds/slave/test', 15:12:43 INFO - 'SHELL': '/bin/bash', 15:12:43 INFO - 'SHLVL': '1', 15:12:43 INFO - 'TERM': 'linux', 15:12:43 INFO - 'TMOUT': '86400', 15:12:43 INFO - 'USER': 'cltbld', 15:12:43 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:43 INFO - '_': '/tools/buildbot/bin/python'} 15:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:43 INFO - Downloading/unpacking jsonschema==2.5.1 15:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:12:45 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 15:12:45 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 15:12:45 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 15:12:45 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 15:12:45 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 15:12:45 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 15:12:45 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:45 INFO - warning: no files found matching '*.txt' 15:12:45 INFO - no previously-included directories found matching 'build' 15:12:45 INFO - no previously-included directories found matching 'dist' 15:12:45 INFO - no previously-included directories found matching '.git*' 15:12:45 INFO - Installing collected packages: jsonschema, functools32 15:12:45 INFO - Running setup.py install for functools32 15:12:45 INFO - warning: no files found matching '*.txt' 15:12:45 INFO - no previously-included directories found matching 'build' 15:12:45 INFO - no previously-included directories found matching 'dist' 15:12:45 INFO - no previously-included directories found matching '.git*' 15:12:45 INFO - Successfully installed jsonschema functools32 15:12:45 INFO - Cleaning up... 15:12:45 INFO - Return code: 0 15:12:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:12:45 INFO - Using env: {'DISPLAY': ':0', 15:12:45 INFO - 'HOME': '/home/cltbld', 15:12:45 INFO - 'LANG': 'en_US.UTF-8', 15:12:45 INFO - 'LANGUAGE': 'en_US:en', 15:12:45 INFO - 'LOGNAME': 'cltbld', 15:12:45 INFO - 'MAIL': '/var/mail/cltbld', 15:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:45 INFO - 'MOZ_NO_REMOTE': '1', 15:12:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:45 INFO - 'NO_EM_RESTART': '1', 15:12:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:45 INFO - 'PWD': '/builds/slave/test', 15:12:45 INFO - 'SHELL': '/bin/bash', 15:12:45 INFO - 'SHLVL': '1', 15:12:45 INFO - 'TERM': 'linux', 15:12:45 INFO - 'TMOUT': '86400', 15:12:45 INFO - 'USER': 'cltbld', 15:12:45 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:45 INFO - '_': '/tools/buildbot/bin/python'} 15:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:12:46 INFO - Cleaning up... 15:12:46 INFO - Return code: 0 15:12:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:46 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:46 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 15:12:46 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 15:12:46 INFO - Using env: {'DISPLAY': ':0', 15:12:46 INFO - 'HOME': '/home/cltbld', 15:12:46 INFO - 'LANG': 'en_US.UTF-8', 15:12:46 INFO - 'LANGUAGE': 'en_US:en', 15:12:46 INFO - 'LOGNAME': 'cltbld', 15:12:46 INFO - 'MAIL': '/var/mail/cltbld', 15:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:46 INFO - 'MOZ_NO_REMOTE': '1', 15:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:46 INFO - 'NO_EM_RESTART': '1', 15:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:46 INFO - 'PWD': '/builds/slave/test', 15:12:46 INFO - 'SHELL': '/bin/bash', 15:12:46 INFO - 'SHLVL': '1', 15:12:46 INFO - 'TERM': 'linux', 15:12:46 INFO - 'TMOUT': '86400', 15:12:46 INFO - 'USER': 'cltbld', 15:12:46 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:46 INFO - '_': '/tools/buildbot/bin/python'} 15:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:46 INFO - Downloading/unpacking blobuploader==1.2.4 15:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:48 INFO - Downloading blobuploader-1.2.4.tar.gz 15:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:12:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:12:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:48 INFO - Downloading docopt-0.6.1.tar.gz 15:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:12:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:12:48 INFO - Running setup.py install for blobuploader 15:12:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:12:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:12:49 INFO - Running setup.py install for requests 15:12:49 INFO - Running setup.py install for docopt 15:12:49 INFO - Successfully installed blobuploader requests docopt 15:12:49 INFO - Cleaning up... 15:12:49 INFO - Return code: 0 15:12:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:49 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:49 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 15:12:49 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 15:12:49 INFO - Using env: {'DISPLAY': ':0', 15:12:49 INFO - 'HOME': '/home/cltbld', 15:12:49 INFO - 'LANG': 'en_US.UTF-8', 15:12:49 INFO - 'LANGUAGE': 'en_US:en', 15:12:49 INFO - 'LOGNAME': 'cltbld', 15:12:49 INFO - 'MAIL': '/var/mail/cltbld', 15:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:49 INFO - 'MOZ_NO_REMOTE': '1', 15:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:49 INFO - 'NO_EM_RESTART': '1', 15:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:49 INFO - 'PWD': '/builds/slave/test', 15:12:49 INFO - 'SHELL': '/bin/bash', 15:12:49 INFO - 'SHLVL': '1', 15:12:49 INFO - 'TERM': 'linux', 15:12:49 INFO - 'TMOUT': '86400', 15:12:49 INFO - 'USER': 'cltbld', 15:12:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:49 INFO - '_': '/tools/buildbot/bin/python'} 15:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:49 INFO - Running setup.py (path:/tmp/pip-1ZOsFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:49 INFO - Running setup.py (path:/tmp/pip-H0DEAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:49 INFO - Running setup.py (path:/tmp/pip-x0cTxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:50 INFO - Running setup.py (path:/tmp/pip-zCu_vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:50 INFO - Running setup.py (path:/tmp/pip-PHKQWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:50 INFO - Running setup.py (path:/tmp/pip-SKqlBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:50 INFO - Running setup.py (path:/tmp/pip-O41YMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:50 INFO - Running setup.py (path:/tmp/pip-ZoFJHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:50 INFO - Running setup.py (path:/tmp/pip-1pZUwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:50 INFO - Running setup.py (path:/tmp/pip-ene67M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:50 INFO - Running setup.py (path:/tmp/pip-zkEtSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:50 INFO - Running setup.py (path:/tmp/pip-J0LFFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:50 INFO - Running setup.py (path:/tmp/pip-DtwsAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:50 INFO - Running setup.py (path:/tmp/pip-MFsgYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:50 INFO - Running setup.py (path:/tmp/pip-K7Wt7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:50 INFO - Running setup.py (path:/tmp/pip-rmoB3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:50 INFO - Running setup.py (path:/tmp/pip-ahRlOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:12:51 INFO - Running setup.py install for manifestparser 15:12:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Running setup.py install for mozcrash 15:12:51 INFO - Running setup.py install for mozdebug 15:12:51 INFO - Running setup.py install for mozdevice 15:12:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Running setup.py install for mozfile 15:12:51 INFO - Running setup.py install for mozhttpd 15:12:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Running setup.py install for mozinfo 15:12:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Running setup.py install for mozInstall 15:12:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:12:51 INFO - Running setup.py install for mozleak 15:12:51 INFO - Running setup.py install for mozlog 15:12:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Running setup.py install for moznetwork 15:12:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Running setup.py install for mozprocess 15:12:52 INFO - Running setup.py install for mozprofile 15:12:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Running setup.py install for mozrunner 15:12:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Running setup.py install for mozscreenshot 15:12:52 INFO - Running setup.py install for moztest 15:12:52 INFO - Running setup.py install for mozversion 15:12:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:12:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:12:52 INFO - Cleaning up... 15:12:52 INFO - Return code: 0 15:12:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:52 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:52 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 15:12:52 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 15:12:52 INFO - Using env: {'DISPLAY': ':0', 15:12:52 INFO - 'HOME': '/home/cltbld', 15:12:52 INFO - 'LANG': 'en_US.UTF-8', 15:12:52 INFO - 'LANGUAGE': 'en_US:en', 15:12:52 INFO - 'LOGNAME': 'cltbld', 15:12:52 INFO - 'MAIL': '/var/mail/cltbld', 15:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:52 INFO - 'MOZ_NO_REMOTE': '1', 15:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:52 INFO - 'NO_EM_RESTART': '1', 15:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:52 INFO - 'PWD': '/builds/slave/test', 15:12:52 INFO - 'SHELL': '/bin/bash', 15:12:52 INFO - 'SHLVL': '1', 15:12:52 INFO - 'TERM': 'linux', 15:12:52 INFO - 'TMOUT': '86400', 15:12:52 INFO - 'USER': 'cltbld', 15:12:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:52 INFO - '_': '/tools/buildbot/bin/python'} 15:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:53 INFO - Running setup.py (path:/tmp/pip-g2KwPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:53 INFO - Running setup.py (path:/tmp/pip-yUrIiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:53 INFO - Running setup.py (path:/tmp/pip-2Xu9c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:53 INFO - Running setup.py (path:/tmp/pip-1SF9IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:53 INFO - Running setup.py (path:/tmp/pip-WZjbuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:53 INFO - Running setup.py (path:/tmp/pip-nSt5w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:53 INFO - Running setup.py (path:/tmp/pip-Mje5Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:53 INFO - Running setup.py (path:/tmp/pip-M62pyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:53 INFO - Running setup.py (path:/tmp/pip-QAZciZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:53 INFO - Running setup.py (path:/tmp/pip-OvULQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:53 INFO - Running setup.py (path:/tmp/pip-NsuDK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:53 INFO - Running setup.py (path:/tmp/pip-kstP6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:53 INFO - Running setup.py (path:/tmp/pip-6riSut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:53 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)) 15:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:53 INFO - Running setup.py (path:/tmp/pip-qIe0oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:54 INFO - Running setup.py (path:/tmp/pip-hY_dMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:54 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)) 15:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:54 INFO - Running setup.py (path:/tmp/pip-4SSlhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:54 INFO - Running setup.py (path:/tmp/pip-q9EMnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:54 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)) 15:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:12:54 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)) 15:12:54 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)) 15:12:54 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)) 15:12:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:56 INFO - Downloading blessings-1.6.tar.gz 15:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:12:56 INFO - Installing collected packages: blessings 15:12:56 INFO - Running setup.py install for blessings 15:12:56 INFO - Successfully installed blessings 15:12:56 INFO - Cleaning up... 15:12:56 INFO - Return code: 0 15:12:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:12:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:56 INFO - Reading from file tmpfile_stdout 15:12:56 INFO - Current package versions: 15:12:56 INFO - argparse == 1.2.1 15:12:56 INFO - blessings == 1.6 15:12:56 INFO - blobuploader == 1.2.4 15:12:56 INFO - docopt == 0.6.1 15:12:56 INFO - functools32 == 3.2.3-2 15:12:56 INFO - jsonschema == 2.5.1 15:12:56 INFO - manifestparser == 1.1 15:12:56 INFO - mozInstall == 1.12 15:12:56 INFO - mozcrash == 0.17 15:12:56 INFO - mozdebug == 0.1 15:12:56 INFO - mozdevice == 0.48 15:12:56 INFO - mozfile == 1.2 15:12:56 INFO - mozhttpd == 0.7 15:12:56 INFO - mozinfo == 0.9 15:12:56 INFO - mozleak == 0.1 15:12:56 INFO - mozlog == 3.4 15:12:56 INFO - moznetwork == 0.27 15:12:56 INFO - mozprocess == 0.23 15:12:56 INFO - mozprofile == 0.28 15:12:56 INFO - mozrunner == 6.12 15:12:56 INFO - mozscreenshot == 0.1 15:12:56 INFO - mozsystemmonitor == 0.3 15:12:56 INFO - moztest == 0.7 15:12:56 INFO - mozversion == 1.4 15:12:56 INFO - psutil == 3.1.1 15:12:56 INFO - requests == 1.2.3 15:12:56 INFO - wsgiref == 0.1.2 15:12:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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')]} 15:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:56 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:56 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 15:12:56 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 15:12:56 INFO - Using env: {'DISPLAY': ':0', 15:12:56 INFO - 'HOME': '/home/cltbld', 15:12:56 INFO - 'LANG': 'en_US.UTF-8', 15:12:56 INFO - 'LANGUAGE': 'en_US:en', 15:12:56 INFO - 'LOGNAME': 'cltbld', 15:12:56 INFO - 'MAIL': '/var/mail/cltbld', 15:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:56 INFO - 'MOZ_NO_REMOTE': '1', 15:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:56 INFO - 'NO_EM_RESTART': '1', 15:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:56 INFO - 'PWD': '/builds/slave/test', 15:12:56 INFO - 'SHELL': '/bin/bash', 15:12:56 INFO - 'SHLVL': '1', 15:12:56 INFO - 'TERM': 'linux', 15:12:56 INFO - 'TMOUT': '86400', 15:12:56 INFO - 'USER': 'cltbld', 15:12:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:56 INFO - '_': '/tools/buildbot/bin/python'} 15:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 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)) 15:12:56 INFO - Cleaning up... 15:12:56 INFO - Return code: 0 15:12:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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')]} 15:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:56 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 0x7f595c83cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a12ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', '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 15:12:56 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 15:12:56 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 15:12:56 INFO - Using env: {'DISPLAY': ':0', 15:12:56 INFO - 'HOME': '/home/cltbld', 15:12:56 INFO - 'LANG': 'en_US.UTF-8', 15:12:56 INFO - 'LANGUAGE': 'en_US:en', 15:12:56 INFO - 'LOGNAME': 'cltbld', 15:12:56 INFO - 'MAIL': '/var/mail/cltbld', 15:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:56 INFO - 'MOZ_NO_REMOTE': '1', 15:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:56 INFO - 'NO_EM_RESTART': '1', 15:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:56 INFO - 'PWD': '/builds/slave/test', 15:12:56 INFO - 'SHELL': '/bin/bash', 15:12:56 INFO - 'SHLVL': '1', 15:12:56 INFO - 'TERM': 'linux', 15:12:56 INFO - 'TMOUT': '86400', 15:12:56 INFO - 'USER': 'cltbld', 15:12:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:56 INFO - '_': '/tools/buildbot/bin/python'} 15:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:12:57 INFO - Cleaning up... 15:12:57 INFO - Return code: 0 15:12:57 INFO - Running post-action listener: _resource_record_post_action 15:12:57 INFO - Running post-action listener: _start_resource_monitoring 15:12:57 INFO - Starting resource monitoring. 15:12:57 INFO - [mozharness: 2016-10-30 22:12:57.157385Z] Finished create-virtualenv step (success) 15:12:57 INFO - [mozharness: 2016-10-30 22:12:57.157885Z] Running install step. 15:12:57 INFO - Running pre-action listener: _resource_record_pre_action 15:12:57 INFO - Running main action method: install 15:12:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:57 INFO - Reading from file tmpfile_stdout 15:12:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:12:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:12:57 INFO - Reading from file tmpfile_stdout 15:12:57 INFO - Output received: 15:12:57 INFO - Usage: mozinstall [options] installer 15:12:57 INFO - Options: 15:12:57 INFO - -h, --help show this help message and exit 15:12:57 INFO - -d DEST, --destination=DEST 15:12:57 INFO - Directory to install application into. [default: 15:12:57 INFO - "/builds/slave/test"] 15:12:57 INFO - --app=APP Application being installed. [default: firefox] 15:12:57 INFO - mkdir: /builds/slave/test/build/application 15:12:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:13:14 INFO - Reading from file tmpfile_stdout 15:13:14 INFO - Output received: 15:13:14 INFO - /builds/slave/test/build/application/firefox/firefox 15:13:14 INFO - Running post-action listener: _resource_record_post_action 15:13:14 INFO - [mozharness: 2016-10-30 22:13:14.426002Z] Finished install step (success) 15:13:14 INFO - [mozharness: 2016-10-30 22:13:14.426276Z] Running run-tests step. 15:13:14 INFO - Running pre-action listener: _resource_record_pre_action 15:13:14 INFO - Running main action method: run_tests 15:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:13:14 INFO - Python 2.7.3 15:13:14 INFO - Return code: 0 15:13:14 INFO - grabbing minidump binary from tooltool 15:13:14 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')]} 15:13:14 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 0x2a0ff60>, '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 0x2a10d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a111d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:13:14 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 15:13:14 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 15:13:14 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 15:13:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:13:14 INFO - Return code: 0 15:13:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:13:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:13:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:13:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:13:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 15:13:14 INFO - Using env: {'DISPLAY': ':0', 15:13:14 INFO - 'HOME': '/home/cltbld', 15:13:14 INFO - 'LANG': 'en_US.UTF-8', 15:13:14 INFO - 'LANGUAGE': 'en_US:en', 15:13:14 INFO - 'LOGNAME': 'cltbld', 15:13:14 INFO - 'MAIL': '/var/mail/cltbld', 15:13:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:13:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:14 INFO - 'MOZ_NO_REMOTE': '1', 15:13:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:14 INFO - 'NO_EM_RESTART': '1', 15:13:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:14 INFO - 'PWD': '/builds/slave/test', 15:13:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:13:14 INFO - 'SHELL': '/bin/bash', 15:13:14 INFO - 'SHLVL': '1', 15:13:14 INFO - 'TERM': 'linux', 15:13:14 INFO - 'TMOUT': '86400', 15:13:14 INFO - 'USER': 'cltbld', 15:13:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577', 15:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:14 INFO - '_': '/tools/buildbot/bin/python'} 15:13:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:13:14 INFO - mozversion application_buildid: 20161029213035 15:13:14 INFO - mozversion application_changeset: bed4b65bc2361fde5a43f12131e88b1883a00be5 15:13:14 INFO - mozversion application_display_name: Nightly 15:13:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:13:14 INFO - mozversion application_name: Firefox 15:13:14 INFO - mozversion application_remotingname: firefox 15:13:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:13:14 INFO - mozversion application_vendor: Mozilla 15:13:14 INFO - mozversion application_version: 52.0a1 15:13:14 INFO - mozversion platform_buildid: 20161029213035 15:13:14 INFO - mozversion platform_changeset: bed4b65bc2361fde5a43f12131e88b1883a00be5 15:13:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:13:14 INFO - mozversion platform_version: 52.0a1 15:13:14 INFO - using testdate: 1477865594 15:13:14 INFO - actual date: 1477865594 15:13:14 INFO - starting webserver on 'localhost:38156' 15:13:14 INFO - SUITE-START | Running 2 tests 15:13:14 INFO - TEST-START | dromaeo_css 15:13:14 INFO - operating with platform_type : linux_ 15:13:14 INFO - Initialising browser for dromaeo_css test... 15:13:14 INFO - TEST-INFO | started process 18578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOrgJkr/profile http://localhost:38156/getInfo.html) 15:13:18 INFO - PROCESS | 18578 | __metrics Screen width/height:1600/1200 15:13:18 INFO - PROCESS | 18578 | colorDepth:24 15:13:18 INFO - PROCESS | 18578 | Browser inner width/height: 1024/697 15:13:18 INFO - PROCESS | 18578 | __metrics 15:13:19 INFO - PROCESS | 18578 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:13:19 INFO - PROCESS | 18578 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:13:19 INFO - PROCESS | 18578 | console.error: PushService: 15:13:19 INFO - PROCESS | 18578 | stateChangeProcessEnqueue: Error transitioning state 15:13:19 INFO - PROCESS | 18578 | UnknownError 15:13:20 INFO - TEST-INFO | 18578: exit 0 15:13:20 INFO - Browser initialized. 15:13:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:13:20 INFO - TEST-INFO | started process 18662 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOrgJkr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:32 INFO - PROCESS | 18662 | RSS: Main: 176705536 15:13:32 INFO - PROCESS | 18662 | 15:14:23 INFO - PROCESS | 18662 | 1477865663472 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:16:34 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38156/tests/dromaeo/cssquery-ext.html) 15:16:36 INFO - PROCESS | 18662 | RSS: Main: 434847744 15:16:36 INFO - PROCESS | 18662 | 15:19:39 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-ext.html (next: http://localhost:38156/tests/dromaeo/cssquery-jquery.html) 15:19:40 INFO - PROCESS | 18662 | RSS: Main: 279613440 15:19:40 INFO - PROCESS | 18662 | 15:22:42 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38156/tests/dromaeo/cssquery-mootools.html) 15:22:42 INFO - PROCESS | 18662 | RSS: Main: 221966336 15:22:42 INFO - PROCESS | 18662 | 15:25:44 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38156/tests/dromaeo/cssquery-prototype.html) 15:25:45 INFO - PROCESS | 18662 | RSS: Main: 264564736 15:25:45 INFO - PROCESS | 18662 | 15:28:48 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38156/tests/dromaeo/cssquery-yui.html) 15:28:48 INFO - PROCESS | 18662 | RSS: Main: 226082816 15:28:48 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-yui.html (next: http://localhost:38156/tests/dromaeo/cssquery-dojo.html) 15:31:51 INFO - PROCESS | 18662 | RSS: Main: 307064832 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | __start_tp_report 15:31:51 INFO - PROCESS | 18662 | _x_x_mozilla_page_load 15:31:51 INFO - PROCESS | 18662 | _x_x_mozilla_page_load_details 15:31:51 INFO - PROCESS | 18662 | |i|pagename|runs| 15:31:51 INFO - PROCESS | 18662 | |0;dojo.html;2951.335949411382;3041.4069256494977;3046.710562496562;3079;3095.829729364884;5256.237845512398;5327.881144959559;5357.491038351351;5376.838694839156;5396.999999999995;5374.7043912584795;5375.381831089426;5394.514493695567;5403.648762830413;5408;4857.465678775341;4882.902341953159;4904.117258893398;4918.950810491903;4940.456549779521;4930.03864246472;4997.000599880021;5000.899982000358;5004.799808007675;5007.84976450707;4878.853634390974;4917.877053073666;4936.876578085558;4937.604991600663;4985.351904252442;4970.105381031413;4993;4999.450060493343;5008.24876268559;5067.645264831426;4747.643926705511;4748.501407352247;4800.663953523254;4897.975510122426;4908.337374454439;5280.260763493114;5290.206469029677;5304.336957880265;5321.8935621287355;5395.217693434469;5652.943470565324;5706.885862282773;5709.600327977043;5710.971445142789;5713.514351280146;5553.694546799924;5665.263515742969;5746.166805813175;5779.046457334534;5819.650820950714;5393.379761327458;5428.4842939920645;5429.049916264638;5488.121900495941;5493.340799104133;5948.970255148696;5952.970235148797;5956.761729530857;5957.344692083867;5961.850953726192;5576.581756368208;5620.297462817148;5717.971410142923;5718.714064296852;5725.799597014085;5549.639273447294;5615.017371959891;5616.831495055155;5644.74598643062;5682.659040457503;5543.251661025791;5552.611317207757;5589.692566908859;5607.074832652648;5610.887782244332;5565.387807341189;5639.548836093103;5642;5659.349174844863;5703.914441283384;4336.804843782037;4361.127774445124;4401.801918913592;4436.489803672554;4451.621612162938;3952.1700442907313;3955.485786847749;3968.7817170055346;4001.91996160081;4002.3596224603925;5514.365847927459;5521.972390138104;5528.308961379827;5528.585356098309;5592.580556458201;5709.25779648643;5747.770089196469;5756.625819321815;5795.623284486495;5867.941320586825;5490.396056433789;5498;5499.917501237484;5545.861353466195;5561.4716601922755;4944.307796908436;4976.552110310089;4984.651074424755;4987.875303117378;5046.798128074909;5762.366139724711;5909.793157239476;5910.408959104056;6023.096535519707;6032.245969253843;5795.478406943395;5805.245318108618;5807.651540907559;5813.215215945793;5815.3603103657715;5980.461758441761;5984.251968503937;5985.700714964321;5997.10043493462;5998.820035398945;5670.461306175906;5714.257146570919;5714.79998200061;5720.942790572041;5721.942780572307;5775.393583673654;5797;5798.623089499169;5798.855028624318;5802.245708057925;5612.438756124355;5640.887182256414;5660;5662.631928924607;5662.971685141712;5517.779288828402;5539.916901246565;5621.747021383966;5642.379338272869;5647.830565083054;5587.944120558743;5592.245046918614;5592.384837667934;5594.468525490072;5595.440455954372;5484.862878428071;5616.943830561642;5644.435556444323;5644.8306550803545;5649.7740090397565;5542.61201715876;5546.417626149358;5569.554435645057;5577.107662773937;5671.461211184931 15:31:51 INFO - PROCESS | 18662 | |1;ext.html;583.6177303865968;599.8290487211143;635.0601110356669;639.9872002559949;646.0632083478956;31617.525737113934;41581.79209103954;43048.3542746859;43236;46284.768576157076;44312.3353149703;44694.10611787762;44851.55148448514;44901.65204568745;44983.32525012128;8682.348823838205;8804.8679269811;8818.118188181195;8818.470891746483;8824;7929.088154862207;8142;10289.948550257239;10497.107745841575;10497.842532362021;10657;10715.839262411031;10722.73193170173;10726.034656880918;10728.73178170544;10510.159187265039;10554.366737995744;10559.73600659982;10562.841557376645;10567.365958042543;10283.691489255334;10353.9482302588;10362.171026317876;10362.533685984148;10371;41752.79123604393;42064.78967605143;42112.57887421117;42135.7359279219;42144.36783448251;3213.389456003352;3238.573601018442;3284.6715328467203;3284.7700660953506;3306.537084808129;30920.845395773104;31078.223044423845;31470;31715.20711982196;32249.35501289961;4235.449391579105;4282.379055037033;4302.139572085564;4302.397664326997;4417.823287068513;322313;323381;324355;325051;325763.9999999976;54200;54349;54425.72787136039;54473.72763136159;54528.45471545313;34247;35006.64993350034;35165.64834351624;36458.45312320317;36509.817450912575;15560;15634.687306254038;15640.2179891005;15642.608934776494;15761.369545218293;11123.443827808576;11407.48666310018;12488.313142777235;12626.494940202288;12640.683982900317;17946.5513362167;18373.7243941341;18636.44090677282;18871.90564047171;19095.80904190968;7018.263082376379;7154.177269613957;7157.821054473679;7232.457565682595;7326.597037163007;982.29274922056;989.1740396768635;1008.8789345278614;1091.6943255888364;1099.0987390340003;31290.37419251602;32233.677663223538;32248.35503289968;32868.178295542806;32990.340193196;1086.2721976275914;1098.3958822647512;1120.1548067907424;1129.0685184722636;1152.3661985907718;16267.913893386176;16592;17347;17363.65272694539;17763.289468421375;496187;497009;499712;501576;506765;56310.310690678525;56603.43396566064;56846.43153568494;56949.59655244792;57052;1230.4278510492663;1269.8730126987227;1296.5721311967218;1335.2989680417859;1376.6420730610114;28558.57162142528;32317.676823231468;32703.509447358785;32864.746008345806;33095.834520828204;47211.52788472209;47640;47652.523474766196;47662;47688.2846757292;383438;386591;387037;387387;387931;26470.205893823215;26471.867640661672;35179.18566514724;36117;38846.80576597099;41995.37006944959;45708.542914570426;46100.07799843917;46254;46722.53277467318;39306.60693393029;39552.80223598864;40746.79626601848;41188;41920;39506.60493395029;39764.40942362273;40018.39972400475;40644.37422503067;41579.37630935478;39673;40512.79743601263;41032.79483602563;41193.794031029654;41209.58790412058;33950;35746.8212658935;35750.642493574735;36284.63715362812;37123.62876371202;27685.58471622887;27893.860530697217;28176.15471535857;28208.43583128367;28216.71783282141 15:31:51 INFO - PROCESS | 18662 | |2;jquery.html;385.97105217108714;386.20441889707206;388.2797410802962;391.27809192040684;413.6835320979449;14221.502247421342;16821.41125060623;19162;19908.303209387675;19997.100130494127;13501.527446539372;14610.415583376653;16531.752023719655;16876.578085547866;16988.405405810798;5431.234848674177;5742.626729262584;5781.508571771405;5807.593468457209;5809.157672137538;5629.155626655994;5729.169270455782;5826.7181220131715;5838.386969368197;5842.88314233715;5618.213450116967;5677.176809362661;5680.091185410335;5680.602357834953;5697.430256974311;5500.092484740013;5661.745221465003;5730;5781.450762177607;5783.5951483396175;5084.669496482717;5086.338775959139;5088.542031217208;5089.236614507817;5093.770780314895;21560;22012.339629810973;22052.228172014064;22095.226667066574;22236.444088897744;2326.7789559991943;2333.0667732906804;2374.4894847607848;2458.6434966929696;2843.7298456646586;13350.399232034582;13871.167729936236;14415.423383064664;14569.271536423243;14618;1837.9172937217725;1859.605296027979;1937.1379736017477;1948.844092472613;1953.8827670339824;79791.02612173479;79936.60031699804;80429.59785201095;80548;82183.17816821815;10010.599576017023;10042.598296068221;10068.949655251823;10108.191344692406;10184.796304073878;13606.727865442635;13635.113717608323;13636.250006249553;13662.590122296348;14191.148531088167;7157.141143062761;7648.158702542716;8058.959705201553;8136.430449868452;8296.917030829734;7185.137783466018;7306.926930730625;7489.101307842985;7559.055118110236;7571.545707257472;6040.154378387029;6121.816345509642;6149.631022138686;6166.229221347332;6193.752249909953;1639.721247387923;1683.8484536391784;1727.8790484666204;1797.604527004057;1845.5143609394297;887.7780554861284;987.3138168972639;987.4174237200086;1011.9595216191417;1049.1868801678606;18780.71828922567;18953.43139705811;18969.525761855788;19281.13234904433;19330.903345483184;971.223021582731;974.2790335152079;976.8681228034125;1003.8193125237382;1023.9897601023895;10158.30804836719;10939.945300273448;11018.44907754609;11021.889781102087;11068.28056176346;401275;404742;406028;406444;406576;51960.22059669108;52256.738716306936;72530;80259;84653;981.7103954333488;999.5202302894651;1025.3950169400089;1025.600015993763;1032.0350472308417;16292.918535407722;16823.158842058092;17146.657066858843;17186.31254749796;17360.87223127876;27193.59209611818;39137;39408.80295598504;40177;40449.595504044584;52022.47977520176;52028.219576705626;52297.41687455374;52657.47342526679;57227.42772572387;5409.675419474686;5425.538829199562;7990.720324788605;8344.748287756885;8925.375223734536;7301.306375894282;9871.950640246752;12547.435365408395;12630.305333206394;12755.234685918504;11359.204855660355;11602.5358985643;11782.234154780255;11934.52261909514;11959.641210763692;10730.785384292427;10965;11198.83201751958;11825.763484730429;12049.397530123633;11124.165687573302;11289.096872250037;11443.885561144281;11755.529778808752;11759;8414.368922330726;8613.612387442456;8695.695650652196;8702.956485217534;8819.250363719155;8902.065283145435;10749.570017199225;11148.609798657008;11302.321860688056;11894.940525297317 15:31:51 INFO - PROCESS | 18662 | |3;mootools.html;1459.9270036498172;1468.5520916120581;1587.6507168422943;1736.548497390678;1754.079108468055;7067.29327067293;7371.88942165868;7927.524348539085;8489.320854331654;8501.319894408449;7982.760517184479;8278.91721082789;8282.296004839596;8482.321414286858;8482.491050536957;2286.3483907086443;2311.04091801902;2316.6293393057113;2317.5133222023396;2329.0683726509446;1960.4904223747706;2298.7816157465013;2306.1813056365;2366.266457398212;2372.086746602562;2038.806313400232;2220.222921977311;2279.794818466338;2287.9084836606517;2307.4462129089065;2245.2815099168274;2249.6513040478753;2250.4148921280503;2260.8078313343467;2282.9200977965693;2274.692916456274;2276.658501224813;2295.724513058427;2299;2302.9078836846506;8391.244787969052;8458.365622578269;8497.532635705096;8509.829803403898;8510;4894.877628059291;5042.243663450475;5050.570701490377;5057.0897238497055;5057.924131138036;3415.9316813663836;3448.2586243957417;3448.603410607762;3449.8792542261153;3528.4883691864793;1088.6407485529755;1094.4527736131934;1109.8279766636185;1148.5578052449785;1166.6033548593518;11914.880851191463;11955;11956.760864782742;11966.282023078644;11985.04119670433;4391.60475557195;4540.931886021646;4600.470945841204;4604.263317869125;4737.123632128068;9902.950485247671;9905.20758339331;9912.306138570231;9913.851292230622;9914.504274786375;5079.796808127633;5086.059079070384;5096.362954630671;5098.133317336062;5099.617528685363;4475.440569928759;4632.351470794091;4641.094986477672;4663.766811659471;4691.413573303337;3746.625337466286;3762.8016186241475;3840.462335273064;3849.4995650565247;3888.7083468739957;1120.3613940054254;1174.5360582569838;1187.2460987273143;1218.9339070275973;1237.1649136832602;785.6778720724476;941.8351788436996;946.8106378724283;968.6609686609771;991.0436428846198;5487.780488780484;5605.943940560543;5683.119116536944;5687.829365119053;5814.127880817827;929.4841363043575;930.3441074042784;931.4737173496952;944.8157609266265;988.6737376665683;3782.735208535376;3914.451976723261;3937.9606203938165;3945.940810887839;3972.5828787977425;10255.487225638688;10570.947145264225;10574.735631609117;10581.89418105809;10626.203034772423;10356.430396328275;10498.475076246157;10500.684979450629;10501.21240906935;10526;7599.392048636096;7897;7916;8071.677132914735;8084.555349455836;5653.236813030188;5666.34836993751;5756.107803290497;5881.353051164454;5889.646621202742;9744.415335079917;9757.316987810786;9768.316217864682;9832.459214743258;9885.258605604464;9618;9647.372920760128;9760.755981100529;9776.973417791314;9782.217422606032;7364.642536643776;7541.509801862861;7594.696212151452;7601.847963040819;7609.021654370014;7759.185285544936;7775.688972441265;7828.45200835936;7828.903953446408;7833.255840695125;7302.96348518254;7319.487635865438;7320.487565870338;7453.739119130804;7559.357454616419;7078;7139.357457828821;7172;7186.676599553137;7228.746993855189;6624.072629831731;6723.7646682365885;6747.257801641914;6749.763758268437;6751.696173672099;4627.120847039052;4661.114388266218;4751;4971.627127965489;5037.974810125926;5054.595632349477;5101.489851015017;5133.8973220534635;5211.1922651989;5238.030964271622 15:31:51 INFO - PROCESS | 18662 | |4;prototype.html;328.1631838811031;332.1712327742283;336.77940948678616;338.1596732120681;343.58769476628055;5258.947410525893;5281.867953301165;5302;5329.706866122362;5332.440093790155;4977.55202031817;5090.643654944146;5107.668001579895;5178.585713142949;5207;4131.3389857622715;4144.6684265258855;4156.5635608261155;4159.480064991876;4197.874063778091;4172.415861779354;4173.6661067114555;4174.1651669666035;4176.477940257468;4181.644560212386;3843.538775346962;3852.268069066882;4086.080631857823;4107.815148318333;4158.334666453368;4086.652634526039;4105.507339119295;4112.136451345221;4127.772972486512;4128.545859955418;3685.963140368589;3688.133288677159;3692.5568931728367;3704.8888533344043;3710.4990826238654;4915.5084491550915;5133.6919784813035;5149.871253218662;5211.062008838408;5235.450277720861;4682.3210634458155;4687.945212327233;4694.178518759203;4695.553922377403;4716.481187069419;5496.697681627509;5500.614956953015;5522.6134170607675;5527.613067085305;5550.47270509301;3867.7746851956053;3927.4501569780255;3962.306596345657;3967.1354463402154;3974.701897357681;5661.235733176051;5664.773409063632;5678.205051292823;5730.341010783772;5742.511886489653;4004.8798536043964;4007.7996100194873;4010.6791456683395;4023.5574086850424;4024.5975402460103;3880.3015457217407;4070.2673518766314;4098.381139449901;4101.405296232029;4111.773852438145;4652.395188625457;4665.370175026328;4668.416447944007;4683.484816670163;4686.6485013624115;5500.202470641734;5514.393416724157;5536.833894983157;5538.806141785019;5563.833085007456;1339.6182088104842;1406.1352268355092;1411.872931436155;1428.5714285714243;1438.2736717957573;872.3457406944791;882.4131952251681;896.7040876510736;900.3067637918766;905.5064989580694;4724.88187795301;4734.4081989751285;4736.526347365236;4754.881127971828;4780.7052614744425;4895.094407534618;4912.852614421573;4989.376327959005;4994.350734404504;5024.62315326352;4653.581177694024;4692.779877231944;4699.741514216683;4716.811327546929;4763.309320148559;2801.9439611207663;3014.633512692526;3030.3333266681293;3033.8028028178096;3040.8783648653807;5868.471837534557;5889.764409423575;5891.587588868823;5917.467427931507;5921.305805858324;5566.526845218121;5750.808301392767;5783.942160578425;5823.708814559255;5841.912371314434;5868.823935281948;5891.7937872175125;5898.578824281821;5904.291485021739;5925.377835327228;5543.610728546432;5681.9431805681415;5692.402297758676;5696.829095127153;5712.6286791358425;5505;5554.944450555605;5591.797763480871;5603.663780173039;5611.38274789781;5884.735186916513;5913.408659134052;5925.911111333247;5961;5974.641521508724;4117.155983023413;4178.415021896998;4201.537830838666;4215.641670457922;4219.940543106223;5604.311623702028;5637.661740295595;5641.379448260606;5654.296435834535;5664.376918538875;5518.448155184449;5530.313254457429;5554.1391084381985;5569.66582005081;5571.415001424792;5323.030478722897;5434.424017035016;5475.780968761205;5486.149646804752;5496.587755918402;5477.616566840283;5484.232207490874;5528.396764938284;5541.916871246854;5545.944540554543;5436.864078398072;5591.552675785846;5596.936582049398;5616.326040875121;5632.746526406403;5415.918761218506;5444.782208711766;5458.181272809111;5459.396798113226;5481.972590137024 15:31:51 INFO - PROCESS | 18662 | |5;yui.html;694.5763084518439;703.8240439890028;713.190528749869;746.8170298276923;772.7565816767718;14386.784198237021;15684.372625094982;15697.29362178702;15710.214489275533;15721.292541835617;13963.720725585508;14131.78802317966;14147.36336864841;14166.504172353969;14184.36170372333;637.2798737426701;651.1047309948821;763.8968739220218;780.8477346917339;789.9881501777478;781.7146741439391;791.8297566023303;792.9484583502083;794.7258195922398;798.9440739148233;841.8021764885247;843.026304618164;843.3843294395095;843.8692002739555;843.9704610338608;671.8018184635545;839.9328053755746;840.794005468661;840.8024114333127;841.4362377207286;509.24377299709903;594.4382558482235;605.9939400605981;612.4365583662997;612.9540284478637;14251.429942802275;14265.857341426557;14271.215083170422;14272.571822845228;14291.642708932255;13578.39759529451;13608.795868061987;13686.384112714952;13776.173429594257;14014.22921739304;12367.876321236763;12370.319632420213;12469.625911222678;12644.936775316157;12728.172668776593;4317.956820431818;4340.7178533395545;4341.45731783527;4341.630961368288;4390.209762242795;60375.69812150955;60382.39617603811;61912.38087619111;62367;64779.02831457579;10812.35125892449;10960.342379457257;10997.835032474359;11100.5559777608;11110.222284440011;19996.100175491847;20028.69956950647;20242.291519797032;20287.28994485186;20429.69355459669;7931.722389716332;7972.311613354365;8125.3499720022255;8197.754067377988;8360.581970901552;4404.537523560044;4409.382686423904;4485.170243504962;4519.615832654261;4525.208088584484;3044.8477576121104;3124.437601231755;3125.937481250408;3128.89048883288;3129.483635200212;597.4443767296369;602.6504627316147;604.1692672575209;615.9568830181844;625.0312016374601;535.2105644174811;546.4863028752959;606.4875180472494;638.457311285413;668.8829454845383;8449.873251901226;8767;8785.640331315613;8959;9498.667546635834;575.8531574448489;579.4495229531962;644.8387903024244;650.4731167754039;658.5393755813267;4134.813933373005;4247.787610619456;4322.394864718942;4347.956520434818;4362.367456718758;48019.51980480221;48358;48369.758151209724;71072;76986;46699.29951050737;64985.35014649887;74596;75442.24557754461;75867.24132758712;69373.3062669377;71281;71897;72110;72248.63875680589;8551.700690475804;8600.053994060774;8746.562671866508;8776.692815751418;8837.293016558788;45974;46864.297035545176;47283.527164727915;47303.763481182374;48493.75753121353;56948.43051569597;60606;62672;63408;65025.67487162534;10892.292001019909;11119.110471162126;11137.888621114009;11142.498587563417;11263.211575189986;12278.469103279389;12293.44679489443;12439.813402799145;12555.87444125547;12972.091953563537;9440.622375104918;11023.72440688957;11814;12020.519179232733;12422.440990155284;12220.75558488843;12421.254724716546;12745;13008.479660813462;13214.207147571176;13016.869831301567;13231.66920826987;13283.601491955256;13509.527166549124;13555.32223388807;10085.385533922135;10108.241881858743;10364.948175259075;10439.94780026095;10564.630237941636;10071.949640251752;10834.891651083388;10878.728031799268;10961.725956850823;10970.616028438966 15:31:51 INFO - PROCESS | 18662 | __end_tp_report 15:31:51 INFO - PROCESS | 18662 | __start_cc_report 15:31:51 INFO - PROCESS | 18662 | _x_x_mozilla_cycle_collect,2684 15:31:51 INFO - PROCESS | 18662 | __end_cc_report 15:31:51 INFO - PROCESS | 18662 | __startTimestamp1477866711478__endTimestamp 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | ------- Summary: start ------- 15:31:51 INFO - PROCESS | 18662 | Number of tests: 6 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#0] dojo.html Cycles:165 Average:5349.05 Median:5561.47 stddev:605.21 (10.9%) stddev-sans-first:577.10 15:31:51 INFO - PROCESS | 18662 | Values: 2951.3 3041.4 3046.7 3079.0 3095.8 5256.2 5327.9 5357.5 5376.8 5397.0 5374.7 5375.4 5394.5 5403.6 5408.0 4857.5 4882.9 4904.1 4919.0 4940.5 4930.0 4997.0 5000.9 5004.8 5007.8 4878.9 4917.9 4936.9 4937.6 4985.4 4970.1 4993.0 4999.5 5008.2 5067.6 4747.6 4748.5 4800.7 4898.0 4908.3 5280.3 5290.2 5304.3 5321.9 5395.2 5652.9 5706.9 5709.6 5711.0 5713.5 5553.7 5665.3 5746.2 5779.0 5819.7 5393.4 5428.5 5429.0 5488.1 5493.3 5949.0 5953.0 5956.8 5957.3 5961.9 5576.6 5620.3 5718.0 5718.7 5725.8 5549.6 5615.0 5616.8 5644.7 5682.7 5543.3 5552.6 5589.7 5607.1 5610.9 5565.4 5639.5 5642.0 5659.3 5703.9 4336.8 4361.1 4401.8 4436.5 4451.6 3952.2 3955.5 3968.8 4001.9 4002.4 5514.4 5522.0 5528.3 5528.6 5592.6 5709.3 5747.8 5756.6 5795.6 5867.9 5490.4 5498.0 5499.9 5545.9 5561.5 4944.3 4976.6 4984.7 4987.9 5046.8 5762.4 5909.8 5910.4 6023.1 6032.2 5795.5 5805.2 5807.7 5813.2 5815.4 5980.5 5984.3 5985.7 5997.1 5998.8 5670.5 5714.3 5714.8 5720.9 5721.9 5775.4 5797.0 5798.6 5798.9 5802.2 5612.4 5640.9 5660.0 5662.6 5663.0 5517.8 5539.9 5621.7 5642.4 5647.8 5587.9 5592.2 5592.4 5594.5 5595.4 5484.9 5616.9 5644.4 5644.8 5649.8 5542.6 5546.4 5569.6 5577.1 5671.5 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#1] ext.html Cycles:180 Average:56226.72 Median:35933.82 stddev:108431.88 (301.8%) stddev-sans-first:108655.58 15:31:51 INFO - PROCESS | 18662 | Values: 583.6 599.8 635.1 640.0 646.1 31617.5 41581.8 43048.4 43236.0 46284.8 44312.3 44694.1 44851.6 44901.7 44983.3 8682.3 8804.9 8818.1 8818.5 8824.0 7929.1 8142.0 10289.9 10497.1 10497.8 10657.0 10715.8 10722.7 10726.0 10728.7 10510.2 10554.4 10559.7 10562.8 10567.4 10283.7 10353.9 10362.2 10362.5 10371.0 41752.8 42064.8 42112.6 42135.7 42144.4 3213.4 3238.6 3284.7 3284.8 3306.5 30920.8 31078.2 31470.0 31715.2 32249.4 4235.4 4282.4 4302.1 4302.4 4417.8 322313.0 323381.0 324355.0 325051.0 325764.0 54200.0 54349.0 54425.7 54473.7 54528.5 34247.0 35006.6 35165.6 36458.5 36509.8 15560.0 15634.7 15640.2 15642.6 15761.4 11123.4 11407.5 12488.3 12626.5 12640.7 17946.6 18373.7 18636.4 18871.9 19095.8 7018.3 7154.2 7157.8 7232.5 7326.6 982.3 989.2 1008.9 1091.7 1099.1 31290.4 32233.7 32248.4 32868.2 32990.3 1086.3 1098.4 1120.2 1129.1 1152.4 16267.9 16592.0 17347.0 17363.7 17763.3 496187.0 497009.0 499712.0 501576.0 506765.0 56310.3 56603.4 56846.4 56949.6 57052.0 1230.4 1269.9 1296.6 1335.3 1376.6 28558.6 32317.7 32703.5 32864.7 33095.8 47211.5 47640.0 47652.5 47662.0 47688.3 383438.0 386591.0 387037.0 387387.0 387931.0 26470.2 26471.9 35179.2 36117.0 38846.8 41995.4 45708.5 46100.1 46254.0 46722.5 39306.6 39552.8 40746.8 41188.0 41920.0 39506.6 39764.4 40018.4 40644.4 41579.4 39673.0 40512.8 41032.8 41193.8 41209.6 33950.0 35746.8 35750.6 36284.6 37123.6 27685.6 27893.9 28176.2 28208.4 28216.7 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#2] jquery.html Cycles:180 Average:25447.56 Median:386.09 stddev:66801.41 (17302.1%) stddev-sans-first:66962.30 15:31:51 INFO - PROCESS | 18662 | Values: 386.0 386.2 388.3 391.3 413.7 14221.5 16821.4 19162.0 19908.3 19997.1 13501.5 14610.4 16531.8 16876.6 16988.4 5431.2 5742.6 5781.5 5807.6 5809.2 5629.2 5729.2 5826.7 5838.4 5842.9 5618.2 5677.2 5680.1 5680.6 5697.4 5500.1 5661.7 5730.0 5781.5 5783.6 5084.7 5086.3 5088.5 5089.2 5093.8 21560.0 22012.3 22052.2 22095.2 22236.4 2326.8 2333.1 2374.5 2458.6 2843.7 13350.4 13871.2 14415.4 14569.3 14618.0 1837.9 1859.6 1937.1 1948.8 1953.9 79791.0 79936.6 80429.6 80548.0 82183.2 10010.6 10042.6 10068.9 10108.2 10184.8 13606.7 13635.1 13636.3 13662.6 14191.1 7157.1 7648.2 8059.0 8136.4 8296.9 7185.1 7306.9 7489.1 7559.1 7571.5 6040.2 6121.8 6149.6 6166.2 6193.8 1639.7 1683.8 1727.9 1797.6 1845.5 887.8 987.3 987.4 1012.0 1049.2 18780.7 18953.4 18969.5 19281.1 19330.9 971.2 974.3 976.9 1003.8 1024.0 10158.3 10939.9 11018.4 11021.9 11068.3 401275.0 404742.0 406028.0 406444.0 406576.0 51960.2 52256.7 72530.0 80259.0 84653.0 981.7 999.5 1025.4 1025.6 1032.0 16292.9 16823.2 17146.7 17186.3 17360.9 27193.6 39137.0 39408.8 40177.0 40449.6 52022.5 52028.2 52297.4 52657.5 57227.4 5409.7 5425.5 7990.7 8344.7 8925.4 7301.3 9872.0 12547.4 12630.3 12755.2 11359.2 11602.5 11782.2 11934.5 11959.6 10730.8 10965.0 11198.8 11825.8 12049.4 11124.2 11289.1 11443.9 11755.5 11759.0 8414.4 8613.6 8695.7 8703.0 8819.3 8902.1 10749.6 11148.6 11302.3 11894.9 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#3] mootools.html Cycles:180 Average:5571.25 Median:5068.86 stddev:3136.93 (61.9%) stddev-sans-first:3130.52 15:31:51 INFO - PROCESS | 18662 | Values: 1459.9 1468.6 1587.7 1736.5 1754.1 7067.3 7371.9 7927.5 8489.3 8501.3 7982.8 8278.9 8282.3 8482.3 8482.5 2286.3 2311.0 2316.6 2317.5 2329.1 1960.5 2298.8 2306.2 2366.3 2372.1 2038.8 2220.2 2279.8 2287.9 2307.4 2245.3 2249.7 2250.4 2260.8 2282.9 2274.7 2276.7 2295.7 2299.0 2302.9 8391.2 8458.4 8497.5 8509.8 8510.0 4894.9 5042.2 5050.6 5057.1 5057.9 3415.9 3448.3 3448.6 3449.9 3528.5 1088.6 1094.5 1109.8 1148.6 1166.6 11914.9 11955.0 11956.8 11966.3 11985.0 4391.6 4540.9 4600.5 4604.3 4737.1 9903.0 9905.2 9912.3 9913.9 9914.5 5079.8 5086.1 5096.4 5098.1 5099.6 4475.4 4632.4 4641.1 4663.8 4691.4 3746.6 3762.8 3840.5 3849.5 3888.7 1120.4 1174.5 1187.2 1218.9 1237.2 785.7 941.8 946.8 968.7 991.0 5487.8 5605.9 5683.1 5687.8 5814.1 929.5 930.3 931.5 944.8 988.7 3782.7 3914.5 3938.0 3945.9 3972.6 10255.5 10570.9 10574.7 10581.9 10626.2 10356.4 10498.5 10500.7 10501.2 10526.0 7599.4 7897.0 7916.0 8071.7 8084.6 5653.2 5666.3 5756.1 5881.4 5889.6 9744.4 9757.3 9768.3 9832.5 9885.3 9618.0 9647.4 9760.8 9777.0 9782.2 7364.6 7541.5 7594.7 7601.8 7609.0 7759.2 7775.7 7828.5 7828.9 7833.3 7303.0 7319.5 7320.5 7453.7 7559.4 7078.0 7139.4 7172.0 7186.7 7228.7 6624.1 6723.8 6747.3 6749.8 6751.7 4627.1 4661.1 4751.0 4971.6 5038.0 5054.6 5101.5 5133.9 5211.2 5238.0 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#4] prototype.html Cycles:180 Average:4609.04 Median:5164.23 stddev:1355.34 (26.2%) stddev-sans-first:1320.51 15:31:51 INFO - PROCESS | 18662 | Values: 328.2 332.2 336.8 338.2 343.6 5258.9 5281.9 5302.0 5329.7 5332.4 4977.6 5090.6 5107.7 5178.6 5207.0 4131.3 4144.7 4156.6 4159.5 4197.9 4172.4 4173.7 4174.2 4176.5 4181.6 3843.5 3852.3 4086.1 4107.8 4158.3 4086.7 4105.5 4112.1 4127.8 4128.5 3686.0 3688.1 3692.6 3704.9 3710.5 4915.5 5133.7 5149.9 5211.1 5235.5 4682.3 4687.9 4694.2 4695.6 4716.5 5496.7 5500.6 5522.6 5527.6 5550.5 3867.8 3927.5 3962.3 3967.1 3974.7 5661.2 5664.8 5678.2 5730.3 5742.5 4004.9 4007.8 4010.7 4023.6 4024.6 3880.3 4070.3 4098.4 4101.4 4111.8 4652.4 4665.4 4668.4 4683.5 4686.6 5500.2 5514.4 5536.8 5538.8 5563.8 1339.6 1406.1 1411.9 1428.6 1438.3 872.3 882.4 896.7 900.3 905.5 4724.9 4734.4 4736.5 4754.9 4780.7 4895.1 4912.9 4989.4 4994.4 5024.6 4653.6 4692.8 4699.7 4716.8 4763.3 2801.9 3014.6 3030.3 3033.8 3040.9 5868.5 5889.8 5891.6 5917.5 5921.3 5566.5 5750.8 5783.9 5823.7 5841.9 5868.8 5891.8 5898.6 5904.3 5925.4 5543.6 5681.9 5692.4 5696.8 5712.6 5505.0 5554.9 5591.8 5603.7 5611.4 5884.7 5913.4 5925.9 5961.0 5974.6 4117.2 4178.4 4201.5 4215.6 4219.9 5604.3 5637.7 5641.4 5654.3 5664.4 5518.4 5530.3 5554.1 5569.7 5571.4 5323.0 5434.4 5475.8 5486.1 5496.6 5477.6 5484.2 5528.4 5541.9 5545.9 5436.9 5591.6 5596.9 5616.3 5632.7 5415.9 5444.8 5458.2 5459.4 5482.0 15:31:51 INFO - PROCESS | 18662 | 15:31:51 INFO - PROCESS | 18662 | [#5] yui.html Cycles:180 Average:16663.98 Median:46781.80 stddev:21168.50 (45.2%) stddev-sans-first:21193.91 15:31:51 INFO - PROCESS | 18662 | Values: 694.6 703.8 713.2 746.8 772.8 14386.8 15684.4 15697.3 15710.2 15721.3 13963.7 14131.8 14147.4 14166.5 14184.4 637.3 651.1 763.9 780.8 790.0 781.7 791.8 792.9 794.7 798.9 841.8 843.0 843.4 843.9 844.0 671.8 839.9 840.8 840.8 841.4 509.2 594.4 606.0 612.4 613.0 14251.4 14265.9 14271.2 14272.6 14291.6 13578.4 13608.8 13686.4 13776.2 14014.2 12367.9 12370.3 12469.6 12644.9 12728.2 4318.0 4340.7 4341.5 4341.6 4390.2 60375.7 60382.4 61912.4 62367.0 64779.0 10812.4 10960.3 10997.8 11100.6 11110.2 19996.1 20028.7 20242.3 20287.3 20429.7 7931.7 7972.3 8125.3 8197.8 8360.6 4404.5 4409.4 4485.2 4519.6 4525.2 3044.8 3124.4 3125.9 3128.9 3129.5 597.4 602.7 604.2 616.0 625.0 535.2 546.5 606.5 638.5 668.9 8449.9 8767.0 8785.6 8959.0 9498.7 575.9 579.4 644.8 650.5 658.5 4134.8 4247.8 4322.4 4348.0 4362.4 48019.5 48358.0 48369.8 71072.0 76986.0 46699.3 64985.4 74596.0 75442.2 75867.2 69373.3 71281.0 71897.0 72110.0 72248.6 8551.7 8600.1 8746.6 8776.7 8837.3 45974.0 46864.3 47283.5 47303.8 48493.8 56948.4 60606.0 62672.0 63408.0 65025.7 10892.3 11119.1 11137.9 11142.5 11263.2 12278.5 12293.4 12439.8 12555.9 12972.1 9440.6 11023.7 11814.0 12020.5 12422.4 12220.8 12421.3 12745.0 13008.5 13214.2 13016.9 13231.7 13283.6 13509.5 13555.3 10085.4 10108.2 10364.9 10439.9 10564.6 10071.9 10834.9 10878.7 10961.7 10970.6 15:31:51 INFO - PROCESS | 18662 | -------- Summary: end -------- 15:31:51 INFO - PROCESS | 18662 | 15:31:52 INFO - TEST-INFO | 18662: exit 0 15:31:52 INFO - TEST-OK | dromaeo_css | took 1117670ms 15:31:52 INFO - TEST-START | kraken 15:31:52 INFO - operating with platform_type : linux_ 15:31:52 INFO - Initialising browser for kraken test... 15:31:52 INFO - TEST-INFO | started process 18977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpItxab_/profile http://localhost:38156/getInfo.html) 15:31:56 INFO - PROCESS | 18977 | __metrics Screen width/height:1600/1200 15:31:56 INFO - PROCESS | 18977 | colorDepth:24 15:31:56 INFO - PROCESS | 18977 | Browser inner width/height: 1024/697 15:31:56 INFO - PROCESS | 18977 | __metrics 15:31:56 INFO - PROCESS | 18977 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:31:56 INFO - PROCESS | 18977 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:31:57 INFO - PROCESS | 18977 | console.error: PushService: 15:31:57 INFO - PROCESS | 18977 | stateChangeProcessEnqueue: Error transitioning state 15:31:57 INFO - PROCESS | 18977 | UnknownError 15:31:57 INFO - TEST-INFO | 18977: exit 0 15:31:57 INFO - Browser initialized. 15:31:57 INFO - Running cycle 1/1 for kraken test... 15:31:57 INFO - TEST-INFO | started process 19060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpItxab_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:32:10 INFO - PROCESS | 19060 | RSS: Main: 174407680 15:32:10 INFO - PROCESS | 19060 | 15:32:12 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-beat-detection) 15:32:13 INFO - PROCESS | 19060 | RSS: Main: 231333888 15:32:13 INFO - PROCESS | 19060 | 15:32:16 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-dft) 15:32:16 INFO - PROCESS | 19060 | RSS: Main: 217194496 15:32:16 INFO - PROCESS | 19060 | 15:32:19 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-fft) 15:32:20 INFO - PROCESS | 19060 | RSS: Main: 299798528 15:32:20 INFO - PROCESS | 19060 | 15:32:22 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-oscillator) 15:32:23 INFO - PROCESS | 19060 | RSS: Main: 210821120 15:32:23 INFO - PROCESS | 19060 | 15:32:25 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:32:25 INFO - PROCESS | 19060 | RSS: Main: 213819392 15:32:25 INFO - PROCESS | 19060 | 15:32:28 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-darkroom) 15:32:29 INFO - PROCESS | 19060 | RSS: Main: 250159104 15:32:29 INFO - PROCESS | 19060 | 15:32:32 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-desaturate) 15:32:33 INFO - PROCESS | 19060 | RSS: Main: 248909824 15:32:33 INFO - PROCESS | 19060 | 15:32:36 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38156/tests/kraken/driver.html?testName=json-parse-financial) 15:32:36 INFO - PROCESS | 19060 | RSS: Main: 248758272 15:32:36 INFO - PROCESS | 19060 | 15:32:38 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:32:38 INFO - PROCESS | 19060 | RSS: Main: 210767872 15:32:38 INFO - PROCESS | 19060 | 15:32:40 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:32:41 INFO - PROCESS | 19060 | RSS: Main: 218402816 15:32:41 INFO - PROCESS | 19060 | 15:32:43 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:32:43 INFO - PROCESS | 19060 | RSS: Main: 228188160 15:32:43 INFO - PROCESS | 19060 | 15:32:46 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:47 INFO - PROCESS | 19060 | RSS: Main: 236642304 15:32:47 INFO - PROCESS | 19060 | 15:32:49 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:50 INFO - PROCESS | 19060 | RSS: Main: 233857024 15:32:50 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38156/tests/kraken/driver.html?testName=ai-astar) 15:32:52 INFO - PROCESS | 19060 | RSS: Main: 283738112 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | __start_tp_report 15:32:52 INFO - PROCESS | 19060 | _x_x_mozilla_page_load 15:32:52 INFO - PROCESS | 19060 | _x_x_mozilla_page_load_details 15:32:52 INFO - PROCESS | 19060 | |i|pagename|runs| 15:32:52 INFO - PROCESS | 19060 | |0;ai-astar;103;115;113;110;111;111;112;111;110;110 15:32:52 INFO - PROCESS | 19060 | |1;audio-beat-detection;150;123;130;165;125;127;123;124;154;129 15:32:52 INFO - PROCESS | 19060 | |2;audio-dft;160;161;162;161;161;161;160;160;161;164 15:32:52 INFO - PROCESS | 19060 | |3;audio-fft;76;76;76;109;77;76;77;77;117;75 15:32:52 INFO - PROCESS | 19060 | |4;audio-oscillator;84;82;119;84;84;83;110;83;84;116 15:32:52 INFO - PROCESS | 19060 | |5;imaging-gaussian-blur;116;117;117;117;117;118;117;117;118;117 15:32:52 INFO - PROCESS | 19060 | |6;imaging-darkroom;145;147;145;146;146;146;146;145;145;145 15:32:52 INFO - PROCESS | 19060 | |7;imaging-desaturate;84;85;84;84;84;84;84;84;85;84 15:32:52 INFO - PROCESS | 19060 | |8;json-parse-financial;53;51;52;53;53;53;53;53;54;60 15:32:52 INFO - PROCESS | 19060 | |9;json-stringify-tinderbox;60;62;60;60;60;60;61;61;60;61 15:32:52 INFO - PROCESS | 19060 | |10;stanford-crypto-aes;80;81;81;80;81;79;79;80;80;80 15:32:52 INFO - PROCESS | 19060 | |11;stanford-crypto-ccm;132;132;142;142;149;129;126;129;178;144 15:32:52 INFO - PROCESS | 19060 | |12;stanford-crypto-pbkdf2;168;167;165;165;169;169;172;164;167;166 15:32:52 INFO - PROCESS | 19060 | |13;stanford-crypto-sha256-iterative;66;68;65;69;64;64;67;64;63;63 15:32:52 INFO - PROCESS | 19060 | __end_tp_report 15:32:52 INFO - PROCESS | 19060 | __start_cc_report 15:32:52 INFO - PROCESS | 19060 | _x_x_mozilla_cycle_collect,1678 15:32:52 INFO - PROCESS | 19060 | __end_cc_report 15:32:52 INFO - PROCESS | 19060 | __startTimestamp1477866772502__endTimestamp 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | ------- Summary: start ------- 15:32:52 INFO - PROCESS | 19060 | Number of tests: 14 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:3.10 (2.8%) stddev-sans-first:1.67 15:32:52 INFO - PROCESS | 19060 | Values: 103.0 115.0 113.0 110.0 111.0 111.0 112.0 111.0 110.0 110.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:129.50 stddev:15.35 (11.9%) stddev-sans-first:15.29 15:32:52 INFO - PROCESS | 19060 | Values: 150.0 123.0 130.0 165.0 125.0 127.0 123.0 124.0 154.0 129.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.20 15:32:52 INFO - PROCESS | 19060 | Values: 160.0 161.0 162.0 161.0 161.0 161.0 160.0 160.0 161.0 164.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:15.62 (20.6%) stddev-sans-first:16.33 15:32:52 INFO - PROCESS | 19060 | Values: 76.0 76.0 76.0 109.0 77.0 76.0 77.0 77.0 117.0 75.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:83.50 stddev:15.42 (18.5%) stddev-sans-first:16.01 15:32:52 INFO - PROCESS | 19060 | Values: 84.0 82.0 119.0 84.0 84.0 83.0 110.0 83.0 84.0 116.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 15:32:52 INFO - PROCESS | 19060 | Values: 116.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 117.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 15:32:52 INFO - PROCESS | 19060 | Values: 145.0 147.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 15:32:52 INFO - PROCESS | 19060 | Values: 84.0 85.0 84.0 84.0 84.0 84.0 84.0 84.0 85.0 84.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:2.42 (4.6%) stddev-sans-first:2.55 15:32:52 INFO - PROCESS | 19060 | Values: 53.0 51.0 52.0 53.0 53.0 53.0 53.0 53.0 54.0 60.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 15:32:52 INFO - PROCESS | 19060 | Values: 60.0 62.0 60.0 60.0 60.0 60.0 61.0 61.0 60.0 61.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 15:32:52 INFO - PROCESS | 19060 | Values: 80.0 81.0 81.0 80.0 81.0 79.0 79.0 80.0 80.0 80.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:142.00 stddev:15.33 (10.8%) stddev-sans-first:15.96 15:32:52 INFO - PROCESS | 19060 | Values: 132.0 132.0 142.0 142.0 149.0 129.0 126.0 129.0 178.0 144.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.39 (1.4%) stddev-sans-first:2.52 15:32:52 INFO - PROCESS | 19060 | Values: 168.0 167.0 165.0 165.0 169.0 169.0 172.0 164.0 167.0 166.0 15:32:52 INFO - PROCESS | 19060 | 15:32:52 INFO - PROCESS | 19060 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:2.11 (3.2%) stddev-sans-first:2.22 15:32:52 INFO - PROCESS | 19060 | Values: 66.0 68.0 65.0 69.0 64.0 64.0 67.0 64.0 63.0 63.0 15:32:52 INFO - PROCESS | 19060 | -------- Summary: end -------- 15:32:52 INFO - PROCESS | 19060 | 15:32:53 INFO - TEST-INFO | 19060: exit 0 15:32:53 INFO - TEST-OK | kraken | took 61268ms 15:32:53 INFO - SUITE-END | took 1178s 15:32:53 INFO - Completed test suite (00:19:38) 15:32:53 INFO - javascript benchmark 15:32:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2951.335949411382, 3041.4069256494977, 3046.710562496562, 3079.0, 3095.829729364884, 5256.237845512398, 5327.881144959559, 5357.491038351351, 5376.838694839156, 5396.999999999995, 5374.7043912584795, 5375.381831089426, 5394.514493695567, 5403.648762830413, 5408.0, 4857.465678775341, 4882.902341953159, 4904.117258893398, 4918.950810491903, 4940.456549779521, 4930.03864246472, 4997.000599880021, 5000.899982000358, 5004.799808007675, 5007.84976450707, 4878.853634390974, 4917.877053073666, 4936.876578085558, 4937.604991600663, 4985.351904252442, 4970.105381031413, 4993.0, 4999.450060493343, 5008.24876268559, 5067.645264831426, 4747.643926705511, 4748.501407352247, 4800.663953523254, 4897.975510122426, 4908.337374454439, 5280.260763493114, 5290.206469029677, 5304.336957880265, 5321.8935621287355, 5395.217693434469, 5652.943470565324, 5706.885862282773, 5709.600327977043, 5710.971445142789, 5713.514351280146, 5553.694546799924, 5665.263515742969, 5746.166805813175, 5779.046457334534, 5819.650820950714, 5393.379761327458, 5428.4842939920645, 5429.049916264638, 5488.121900495941, 5493.340799104133, 5948.970255148696, 5952.970235148797, 5956.761729530857, 5957.344692083867, 5961.850953726192, 5576.581756368208, 5620.297462817148, 5717.971410142923, 5718.714064296852, 5725.799597014085, 5549.639273447294, 5615.017371959891, 5616.831495055155, 5644.74598643062, 5682.659040457503, 5543.251661025791, 5552.611317207757, 5589.692566908859, 5607.074832652648, 5610.887782244332, 5565.387807341189, 5639.548836093103, 5642.0, 5659.349174844863, 5703.914441283384, 4336.804843782037, 4361.127774445124, 4401.801918913592, 4436.489803672554, 4451.621612162938, 3952.1700442907313, 3955.485786847749, 3968.7817170055346, 4001.91996160081, 4002.3596224603925, 5514.365847927459, 5521.972390138104, 5528.308961379827, 5528.585356098309, 5592.580556458201, 5709.25779648643, 5747.770089196469, 5756.625819321815, 5795.623284486495, 5867.941320586825, 5490.396056433789, 5498.0, 5499.917501237484, 5545.861353466195, 5561.4716601922755, 4944.307796908436, 4976.552110310089, 4984.651074424755, 4987.875303117378, 5046.798128074909, 5762.366139724711, 5909.793157239476, 5910.408959104056, 6023.096535519707, 6032.245969253843, 5795.478406943395, 5805.245318108618, 5807.651540907559, 5813.215215945793, 5815.3603103657715, 5980.461758441761, 5984.251968503937, 5985.700714964321, 5997.10043493462, 5998.820035398945, 5670.461306175906, 5714.257146570919, 5714.79998200061, 5720.942790572041, 5721.942780572307, 5775.393583673654, 5797.0, 5798.623089499169, 5798.855028624318, 5802.245708057925, 5612.438756124355, 5640.887182256414, 5660.0, 5662.631928924607, 5662.971685141712, 5517.779288828402, 5539.916901246565, 5621.747021383966, 5642.379338272869, 5647.830565083054, 5587.944120558743, 5592.245046918614, 5592.384837667934, 5594.468525490072, 5595.440455954372, 5484.862878428071, 5616.943830561642, 5644.435556444323, 5644.8306550803545, 5649.7740090397565, 5542.61201715876, 5546.417626149358, 5569.554435645057, 5577.107662773937, 5671.461211184931], "value": 5307.639300047007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.6177303865968, 599.8290487211143, 635.0601110356669, 639.9872002559949, 646.0632083478956, 31617.525737113934, 41581.79209103954, 43048.3542746859, 43236.0, 46284.768576157076, 44312.3353149703, 44694.10611787762, 44851.55148448514, 44901.65204568745, 44983.32525012128, 8682.348823838205, 8804.8679269811, 8818.118188181195, 8818.470891746483, 8824.0, 7929.088154862207, 8142.0, 10289.948550257239, 10497.107745841575, 10497.842532362021, 10657.0, 10715.839262411031, 10722.73193170173, 10726.034656880918, 10728.73178170544, 10510.159187265039, 10554.366737995744, 10559.73600659982, 10562.841557376645, 10567.365958042543, 10283.691489255334, 10353.9482302588, 10362.171026317876, 10362.533685984148, 10371.0, 41752.79123604393, 42064.78967605143, 42112.57887421117, 42135.7359279219, 42144.36783448251, 3213.389456003352, 3238.573601018442, 3284.6715328467203, 3284.7700660953506, 3306.537084808129, 30920.845395773104, 31078.223044423845, 31470.0, 31715.20711982196, 32249.35501289961, 4235.449391579105, 4282.379055037033, 4302.139572085564, 4302.397664326997, 4417.823287068513, 322313.0, 323381.0, 324355.0, 325051.0, 325763.9999999976, 54200.0, 54349.0, 54425.72787136039, 54473.72763136159, 54528.45471545313, 34247.0, 35006.64993350034, 35165.64834351624, 36458.45312320317, 36509.817450912575, 15560.0, 15634.687306254038, 15640.2179891005, 15642.608934776494, 15761.369545218293, 11123.443827808576, 11407.48666310018, 12488.313142777235, 12626.494940202288, 12640.683982900317, 17946.5513362167, 18373.7243941341, 18636.44090677282, 18871.90564047171, 19095.80904190968, 7018.263082376379, 7154.177269613957, 7157.821054473679, 7232.457565682595, 7326.597037163007, 982.29274922056, 989.1740396768635, 1008.8789345278614, 1091.6943255888364, 1099.0987390340003, 31290.37419251602, 32233.677663223538, 32248.35503289968, 32868.178295542806, 32990.340193196, 1086.2721976275914, 1098.3958822647512, 1120.1548067907424, 1129.0685184722636, 1152.3661985907718, 16267.913893386176, 16592.0, 17347.0, 17363.65272694539, 17763.289468421375, 496187.0, 497009.0, 499712.0, 501576.0, 506765.0, 56310.310690678525, 56603.43396566064, 56846.43153568494, 56949.59655244792, 57052.0, 1230.4278510492663, 1269.8730126987227, 1296.5721311967218, 1335.2989680417859, 1376.6420730610114, 28558.57162142528, 32317.676823231468, 32703.509447358785, 32864.746008345806, 33095.834520828204, 47211.52788472209, 47640.0, 47652.523474766196, 47662.0, 47688.2846757292, 383438.0, 386591.0, 387037.0, 387387.0, 387931.0, 26470.205893823215, 26471.867640661672, 35179.18566514724, 36117.0, 38846.80576597099, 41995.37006944959, 45708.542914570426, 46100.07799843917, 46254.0, 46722.53277467318, 39306.60693393029, 39552.80223598864, 40746.79626601848, 41188.0, 41920.0, 39506.60493395029, 39764.40942362273, 40018.39972400475, 40644.37422503067, 41579.37630935478, 39673.0, 40512.79743601263, 41032.79483602563, 41193.794031029654, 41209.58790412058, 33950.0, 35746.8212658935, 35750.642493574735, 36284.63715362812, 37123.62876371202, 27685.58471622887, 27893.860530697217, 28176.15471535857, 28208.43583128367, 28216.71783282141], "value": 19762.76461606501, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.97105217108714, 386.20441889707206, 388.2797410802962, 391.27809192040684, 413.6835320979449, 14221.502247421342, 16821.41125060623, 19162.0, 19908.303209387675, 19997.100130494127, 13501.527446539372, 14610.415583376653, 16531.752023719655, 16876.578085547866, 16988.405405810798, 5431.234848674177, 5742.626729262584, 5781.508571771405, 5807.593468457209, 5809.157672137538, 5629.155626655994, 5729.169270455782, 5826.7181220131715, 5838.386969368197, 5842.88314233715, 5618.213450116967, 5677.176809362661, 5680.091185410335, 5680.602357834953, 5697.430256974311, 5500.092484740013, 5661.745221465003, 5730.0, 5781.450762177607, 5783.5951483396175, 5084.669496482717, 5086.338775959139, 5088.542031217208, 5089.236614507817, 5093.770780314895, 21560.0, 22012.339629810973, 22052.228172014064, 22095.226667066574, 22236.444088897744, 2326.7789559991943, 2333.0667732906804, 2374.4894847607848, 2458.6434966929696, 2843.7298456646586, 13350.399232034582, 13871.167729936236, 14415.423383064664, 14569.271536423243, 14618.0, 1837.9172937217725, 1859.605296027979, 1937.1379736017477, 1948.844092472613, 1953.8827670339824, 79791.02612173479, 79936.60031699804, 80429.59785201095, 80548.0, 82183.17816821815, 10010.599576017023, 10042.598296068221, 10068.949655251823, 10108.191344692406, 10184.796304073878, 13606.727865442635, 13635.113717608323, 13636.250006249553, 13662.590122296348, 14191.148531088167, 7157.141143062761, 7648.158702542716, 8058.959705201553, 8136.430449868452, 8296.917030829734, 7185.137783466018, 7306.926930730625, 7489.101307842985, 7559.055118110236, 7571.545707257472, 6040.154378387029, 6121.816345509642, 6149.631022138686, 6166.229221347332, 6193.752249909953, 1639.721247387923, 1683.8484536391784, 1727.8790484666204, 1797.604527004057, 1845.5143609394297, 887.7780554861284, 987.3138168972639, 987.4174237200086, 1011.9595216191417, 1049.1868801678606, 18780.71828922567, 18953.43139705811, 18969.525761855788, 19281.13234904433, 19330.903345483184, 971.223021582731, 974.2790335152079, 976.8681228034125, 1003.8193125237382, 1023.9897601023895, 10158.30804836719, 10939.945300273448, 11018.44907754609, 11021.889781102087, 11068.28056176346, 401275.0, 404742.0, 406028.0, 406444.0, 406576.0, 51960.22059669108, 52256.738716306936, 72530.0, 80259.0, 84653.0, 981.7103954333488, 999.5202302894651, 1025.3950169400089, 1025.600015993763, 1032.0350472308417, 16292.918535407722, 16823.158842058092, 17146.657066858843, 17186.31254749796, 17360.87223127876, 27193.59209611818, 39137.0, 39408.80295598504, 40177.0, 40449.595504044584, 52022.47977520176, 52028.219576705626, 52297.41687455374, 52657.47342526679, 57227.42772572387, 5409.675419474686, 5425.538829199562, 7990.720324788605, 8344.748287756885, 8925.375223734536, 7301.306375894282, 9871.950640246752, 12547.435365408395, 12630.305333206394, 12755.234685918504, 11359.204855660355, 11602.5358985643, 11782.234154780255, 11934.52261909514, 11959.641210763692, 10730.785384292427, 10965.0, 11198.83201751958, 11825.763484730429, 12049.397530123633, 11124.165687573302, 11289.096872250037, 11443.885561144281, 11755.529778808752, 11759.0, 8414.368922330726, 8613.612387442456, 8695.695650652196, 8702.956485217534, 8819.250363719155, 8902.065283145435, 10749.570017199225, 11148.609798657008, 11302.321860688056, 11894.940525297317], "value": 8869.808667889987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.9270036498172, 1468.5520916120581, 1587.6507168422943, 1736.548497390678, 1754.079108468055, 7067.29327067293, 7371.88942165868, 7927.524348539085, 8489.320854331654, 8501.319894408449, 7982.760517184479, 8278.91721082789, 8282.296004839596, 8482.321414286858, 8482.491050536957, 2286.3483907086443, 2311.04091801902, 2316.6293393057113, 2317.5133222023396, 2329.0683726509446, 1960.4904223747706, 2298.7816157465013, 2306.1813056365, 2366.266457398212, 2372.086746602562, 2038.806313400232, 2220.222921977311, 2279.794818466338, 2287.9084836606517, 2307.4462129089065, 2245.2815099168274, 2249.6513040478753, 2250.4148921280503, 2260.8078313343467, 2282.9200977965693, 2274.692916456274, 2276.658501224813, 2295.724513058427, 2299.0, 2302.9078836846506, 8391.244787969052, 8458.365622578269, 8497.532635705096, 8509.829803403898, 8510.0, 4894.877628059291, 5042.243663450475, 5050.570701490377, 5057.0897238497055, 5057.924131138036, 3415.9316813663836, 3448.2586243957417, 3448.603410607762, 3449.8792542261153, 3528.4883691864793, 1088.6407485529755, 1094.4527736131934, 1109.8279766636185, 1148.5578052449785, 1166.6033548593518, 11914.880851191463, 11955.0, 11956.760864782742, 11966.282023078644, 11985.04119670433, 4391.60475557195, 4540.931886021646, 4600.470945841204, 4604.263317869125, 4737.123632128068, 9902.950485247671, 9905.20758339331, 9912.306138570231, 9913.851292230622, 9914.504274786375, 5079.796808127633, 5086.059079070384, 5096.362954630671, 5098.133317336062, 5099.617528685363, 4475.440569928759, 4632.351470794091, 4641.094986477672, 4663.766811659471, 4691.413573303337, 3746.625337466286, 3762.8016186241475, 3840.462335273064, 3849.4995650565247, 3888.7083468739957, 1120.3613940054254, 1174.5360582569838, 1187.2460987273143, 1218.9339070275973, 1237.1649136832602, 785.6778720724476, 941.8351788436996, 946.8106378724283, 968.6609686609771, 991.0436428846198, 5487.780488780484, 5605.943940560543, 5683.119116536944, 5687.829365119053, 5814.127880817827, 929.4841363043575, 930.3441074042784, 931.4737173496952, 944.8157609266265, 988.6737376665683, 3782.735208535376, 3914.451976723261, 3937.9606203938165, 3945.940810887839, 3972.5828787977425, 10255.487225638688, 10570.947145264225, 10574.735631609117, 10581.89418105809, 10626.203034772423, 10356.430396328275, 10498.475076246157, 10500.684979450629, 10501.21240906935, 10526.0, 7599.392048636096, 7897.0, 7916.0, 8071.677132914735, 8084.555349455836, 5653.236813030188, 5666.34836993751, 5756.107803290497, 5881.353051164454, 5889.646621202742, 9744.415335079917, 9757.316987810786, 9768.316217864682, 9832.459214743258, 9885.258605604464, 9618.0, 9647.372920760128, 9760.755981100529, 9776.973417791314, 9782.217422606032, 7364.642536643776, 7541.509801862861, 7594.696212151452, 7601.847963040819, 7609.021654370014, 7759.185285544936, 7775.688972441265, 7828.45200835936, 7828.903953446408, 7833.255840695125, 7302.96348518254, 7319.487635865438, 7320.487565870338, 7453.739119130804, 7559.357454616419, 7078.0, 7139.357457828821, 7172.0, 7186.676599553137, 7228.746993855189, 6624.072629831731, 6723.7646682365885, 6747.257801641914, 6749.763758268437, 6751.696173672099, 4627.120847039052, 4661.114388266218, 4751.0, 4971.627127965489, 5037.974810125926, 5054.595632349477, 5101.489851015017, 5133.8973220534635, 5211.1922651989, 5238.030964271622], "value": 4484.655602431772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.1631838811031, 332.1712327742283, 336.77940948678616, 338.1596732120681, 343.58769476628055, 5258.947410525893, 5281.867953301165, 5302.0, 5329.706866122362, 5332.440093790155, 4977.55202031817, 5090.643654944146, 5107.668001579895, 5178.585713142949, 5207.0, 4131.3389857622715, 4144.6684265258855, 4156.5635608261155, 4159.480064991876, 4197.874063778091, 4172.415861779354, 4173.6661067114555, 4174.1651669666035, 4176.477940257468, 4181.644560212386, 3843.538775346962, 3852.268069066882, 4086.080631857823, 4107.815148318333, 4158.334666453368, 4086.652634526039, 4105.507339119295, 4112.136451345221, 4127.772972486512, 4128.545859955418, 3685.963140368589, 3688.133288677159, 3692.5568931728367, 3704.8888533344043, 3710.4990826238654, 4915.5084491550915, 5133.6919784813035, 5149.871253218662, 5211.062008838408, 5235.450277720861, 4682.3210634458155, 4687.945212327233, 4694.178518759203, 4695.553922377403, 4716.481187069419, 5496.697681627509, 5500.614956953015, 5522.6134170607675, 5527.613067085305, 5550.47270509301, 3867.7746851956053, 3927.4501569780255, 3962.306596345657, 3967.1354463402154, 3974.701897357681, 5661.235733176051, 5664.773409063632, 5678.205051292823, 5730.341010783772, 5742.511886489653, 4004.8798536043964, 4007.7996100194873, 4010.6791456683395, 4023.5574086850424, 4024.5975402460103, 3880.3015457217407, 4070.2673518766314, 4098.381139449901, 4101.405296232029, 4111.773852438145, 4652.395188625457, 4665.370175026328, 4668.416447944007, 4683.484816670163, 4686.6485013624115, 5500.202470641734, 5514.393416724157, 5536.833894983157, 5538.806141785019, 5563.833085007456, 1339.6182088104842, 1406.1352268355092, 1411.872931436155, 1428.5714285714243, 1438.2736717957573, 872.3457406944791, 882.4131952251681, 896.7040876510736, 900.3067637918766, 905.5064989580694, 4724.88187795301, 4734.4081989751285, 4736.526347365236, 4754.881127971828, 4780.7052614744425, 4895.094407534618, 4912.852614421573, 4989.376327959005, 4994.350734404504, 5024.62315326352, 4653.581177694024, 4692.779877231944, 4699.741514216683, 4716.811327546929, 4763.309320148559, 2801.9439611207663, 3014.633512692526, 3030.3333266681293, 3033.8028028178096, 3040.8783648653807, 5868.471837534557, 5889.764409423575, 5891.587588868823, 5917.467427931507, 5921.305805858324, 5566.526845218121, 5750.808301392767, 5783.942160578425, 5823.708814559255, 5841.912371314434, 5868.823935281948, 5891.7937872175125, 5898.578824281821, 5904.291485021739, 5925.377835327228, 5543.610728546432, 5681.9431805681415, 5692.402297758676, 5696.829095127153, 5712.6286791358425, 5505.0, 5554.944450555605, 5591.797763480871, 5603.663780173039, 5611.38274789781, 5884.735186916513, 5913.408659134052, 5925.911111333247, 5961.0, 5974.641521508724, 4117.155983023413, 4178.415021896998, 4201.537830838666, 4215.641670457922, 4219.940543106223, 5604.311623702028, 5637.661740295595, 5641.379448260606, 5654.296435834535, 5664.376918538875, 5518.448155184449, 5530.313254457429, 5554.1391084381985, 5569.66582005081, 5571.415001424792, 5323.030478722897, 5434.424017035016, 5475.780968761205, 5486.149646804752, 5496.587755918402, 5477.616566840283, 5484.232207490874, 5528.396764938284, 5541.916871246854, 5545.944540554543, 5436.864078398072, 5591.552675785846, 5596.936582049398, 5616.326040875121, 5632.746526406403, 5415.918761218506, 5444.782208711766, 5458.181272809111, 5459.396798113226, 5481.972590137024], "value": 4177.184673694063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.5763084518439, 703.8240439890028, 713.190528749869, 746.8170298276923, 772.7565816767718, 14386.784198237021, 15684.372625094982, 15697.29362178702, 15710.214489275533, 15721.292541835617, 13963.720725585508, 14131.78802317966, 14147.36336864841, 14166.504172353969, 14184.36170372333, 637.2798737426701, 651.1047309948821, 763.8968739220218, 780.8477346917339, 789.9881501777478, 781.7146741439391, 791.8297566023303, 792.9484583502083, 794.7258195922398, 798.9440739148233, 841.8021764885247, 843.026304618164, 843.3843294395095, 843.8692002739555, 843.9704610338608, 671.8018184635545, 839.9328053755746, 840.794005468661, 840.8024114333127, 841.4362377207286, 509.24377299709903, 594.4382558482235, 605.9939400605981, 612.4365583662997, 612.9540284478637, 14251.429942802275, 14265.857341426557, 14271.215083170422, 14272.571822845228, 14291.642708932255, 13578.39759529451, 13608.795868061987, 13686.384112714952, 13776.173429594257, 14014.22921739304, 12367.876321236763, 12370.319632420213, 12469.625911222678, 12644.936775316157, 12728.172668776593, 4317.956820431818, 4340.7178533395545, 4341.45731783527, 4341.630961368288, 4390.209762242795, 60375.69812150955, 60382.39617603811, 61912.38087619111, 62367.0, 64779.02831457579, 10812.35125892449, 10960.342379457257, 10997.835032474359, 11100.5559777608, 11110.222284440011, 19996.100175491847, 20028.69956950647, 20242.291519797032, 20287.28994485186, 20429.69355459669, 7931.722389716332, 7972.311613354365, 8125.3499720022255, 8197.754067377988, 8360.581970901552, 4404.537523560044, 4409.382686423904, 4485.170243504962, 4519.615832654261, 4525.208088584484, 3044.8477576121104, 3124.437601231755, 3125.937481250408, 3128.89048883288, 3129.483635200212, 597.4443767296369, 602.6504627316147, 604.1692672575209, 615.9568830181844, 625.0312016374601, 535.2105644174811, 546.4863028752959, 606.4875180472494, 638.457311285413, 668.8829454845383, 8449.873251901226, 8767.0, 8785.640331315613, 8959.0, 9498.667546635834, 575.8531574448489, 579.4495229531962, 644.8387903024244, 650.4731167754039, 658.5393755813267, 4134.813933373005, 4247.787610619456, 4322.394864718942, 4347.956520434818, 4362.367456718758, 48019.51980480221, 48358.0, 48369.758151209724, 71072.0, 76986.0, 46699.29951050737, 64985.35014649887, 74596.0, 75442.24557754461, 75867.24132758712, 69373.3062669377, 71281.0, 71897.0, 72110.0, 72248.63875680589, 8551.700690475804, 8600.053994060774, 8746.562671866508, 8776.692815751418, 8837.293016558788, 45974.0, 46864.297035545176, 47283.527164727915, 47303.763481182374, 48493.75753121353, 56948.43051569597, 60606.0, 62672.0, 63408.0, 65025.67487162534, 10892.292001019909, 11119.110471162126, 11137.888621114009, 11142.498587563417, 11263.211575189986, 12278.469103279389, 12293.44679489443, 12439.813402799145, 12555.87444125547, 12972.091953563537, 9440.622375104918, 11023.72440688957, 11814.0, 12020.519179232733, 12422.440990155284, 12220.75558488843, 12421.254724716546, 12745.0, 13008.479660813462, 13214.207147571176, 13016.869831301567, 13231.66920826987, 13283.601491955256, 13509.527166549124, 13555.32223388807, 10085.385533922135, 10108.241881858743, 10364.948175259075, 10439.94780026095, 10564.630237941636, 10071.949640251752, 10834.891651083388, 10878.728031799268, 10961.725956850823, 10970.616028438966], "value": 6832.845897697238, "unit": "score"}], "name": "dromaeo_css", "value": 7014.346725146251, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 113.0, 110.0, 111.0, 111.0, 112.0, 111.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 130.0, 165.0, 125.0, 127.0, 123.0, 124.0, 154.0, 129.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 164.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 109.0, 77.0, 76.0, 77.0, 77.0, 117.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 119.0, 84.0, 84.0, 83.0, 110.0, 83.0, 84.0, 116.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 60.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 81.0, 80.0, 81.0, 79.0, 79.0, 80.0, 80.0, 80.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 142.0, 142.0, 149.0, 129.0, 126.0, 129.0, 178.0, 144.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 165.0, 165.0, 169.0, 169.0, 172.0, 164.0, 167.0, 166.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 69.0, 64.0, 64.0, 67.0, 64.0, 63.0, 63.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1496.9999999999998, "alertThreshold": 2.0}]} 15:32:53 INFO - Return code: 0 15:32:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:32:53 INFO - # TBPL SUCCESS # 15:32:53 INFO - Running post-action listener: _resource_record_post_action 15:32:53 INFO - [mozharness: 2016-10-30 22:32:53.799446Z] Finished run-tests step (success) 15:32:53 INFO - Running post-run listener: _resource_record_post_run 15:32:53 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 403755008; Read time: 16; Write time: 267756 15:32:53 INFO - TinderboxPrint: CPU usage
13.1% 15:32:53 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 15:32:53 INFO - TinderboxPrint: I/O write bytes / time
403,755,008 / 267,756 15:32:53 INFO - TinderboxPrint: CPU idle
8,294.8 (86.9%) 15:32:53 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 15:32:53 INFO - TinderboxPrint: Swap in / out
0 / 0 15:32:53 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114819072; Read time: 0; Write time: 104292 15:32:53 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288935936; Read time: 0; Write time: 163464 15:32:54 INFO - Running post-run listener: _upload_blobber_files 15:32:54 INFO - Blob upload gear active. 15:32:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:32:54 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:57 INFO - (blobuploader) - INFO - Done attempting. 15:32:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:32:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:32:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:58 INFO - (blobuploader) - INFO - Done attempting. 15:32:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:32:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:32:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:59 INFO - (blobuploader) - INFO - Done attempting. 15:32:59 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:59 INFO - Return code: 0 15:32:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b79a002684c2bc7aefb3305ea8627ca3bd0219a4517fdbfb0f4a85daae1ac84bfb7a02853c598d4cffce43bd1b9bdd85c0bd888e7a49be278f256a06c474963d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22b9451bb16f25c7ff759bb1c43a47701346f14ed6e5f60f66b45dd18a575a29f459570316cb68201c61372c5d835f9031390d0192b9976a338b4fb93f4e7d8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87021b88509ea399ce4571f93ab5c514d45e7636a6cd615e7fabc5e4b35c87c85460728aabecd7873ca3e65afb4738c7a0477c64ccda811963b5f33bbeea8c72"} 15:32:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:59 INFO - Contents: 15:32:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b79a002684c2bc7aefb3305ea8627ca3bd0219a4517fdbfb0f4a85daae1ac84bfb7a02853c598d4cffce43bd1b9bdd85c0bd888e7a49be278f256a06c474963d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22b9451bb16f25c7ff759bb1c43a47701346f14ed6e5f60f66b45dd18a575a29f459570316cb68201c61372c5d835f9031390d0192b9976a338b4fb93f4e7d8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87021b88509ea399ce4571f93ab5c514d45e7636a6cd615e7fabc5e4b35c87c85460728aabecd7873ca3e65afb4738c7a0477c64ccda811963b5f33bbeea8c72"} 15:32:59 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:59 INFO - Copying logs to upload dir... 15:32:59 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.964296 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-10-30 15:32:59.371643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 15:32:59.374737) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 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-Inbound/sha512/b79a002684c2bc7aefb3305ea8627ca3bd0219a4517fdbfb0f4a85daae1ac84bfb7a02853c598d4cffce43bd1b9bdd85c0bd888e7a49be278f256a06c474963d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22b9451bb16f25c7ff759bb1c43a47701346f14ed6e5f60f66b45dd18a575a29f459570316cb68201c61372c5d835f9031390d0192b9976a338b4fb93f4e7d8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87021b88509ea399ce4571f93ab5c514d45e7636a6cd615e7fabc5e4b35c87c85460728aabecd7873ca3e65afb4738c7a0477c64ccda811963b5f33bbeea8c72"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011502 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477801835/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b79a002684c2bc7aefb3305ea8627ca3bd0219a4517fdbfb0f4a85daae1ac84bfb7a02853c598d4cffce43bd1b9bdd85c0bd888e7a49be278f256a06c474963d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22b9451bb16f25c7ff759bb1c43a47701346f14ed6e5f60f66b45dd18a575a29f459570316cb68201c61372c5d835f9031390d0192b9976a338b4fb93f4e7d8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87021b88509ea399ce4571f93ab5c514d45e7636a6cd615e7fabc5e4b35c87c85460728aabecd7873ca3e65afb4738c7a0477c64ccda811963b5f33bbeea8c72"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 15:32:59.400258) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:32:59.400606) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1477865540.984166-1350038577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:32:59.482213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 15:32:59.482580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 15:32:59.482940) ========= ========= Total master_lag: 0.15 =========