builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1476685019.01 results: success (0) buildid: 20161016201450 builduid: 224945c9e2144f3e8f3a341954ac39aa revision: 2976adccde9b75596c469428228177ed3d0f0b61 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.014191) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.014669) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.014960) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.057627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.058013) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-16 23:17:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 24.3M=0s 2016-10-16 23:17:00 (24.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103287 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.177760) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.178072) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015905 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.228918) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.229406) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2976adccde9b75596c469428228177ed3d0f0b61 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2976adccde9b75596c469428228177ed3d0f0b61 --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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-16 23:17:00,612 truncating revision to first 12 chars 2016-10-16 23:17:00,612 Setting DEBUG logging. 2016-10-16 23:17:00,613 attempt 1/10 2016-10-16 23:17:00,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2976adccde9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 23:17:00,885 unpacking tar archive at: fx-team-2976adccde9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675873 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.919600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.920042) ========= script_repo_revision: 2976adccde9b75596c469428228177ed3d0f0b61 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.920545) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.920819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 23:16:59.931746) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-10-16 23:16:59.932205) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:17:01 INFO - MultiFileLogger online at 20161016 23:17:01 in /builds/slave/test 23:17:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:17:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:17:01 INFO - {'append_to_log': False, 23:17:01 INFO - 'base_work_dir': '/builds/slave/test', 23:17:01 INFO - 'blob_upload_branch': 'Fx-Team', 23:17:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:17:01 INFO - 'branch': 'Fx-Team', 23:17:01 INFO - 'buildbot_json_path': 'buildprops.json', 23:17:01 INFO - 'config_files': ('talos/linux_config.py',), 23:17:01 INFO - 'default_actions': ('clobber', 23:17:01 INFO - 'read-buildbot-config', 23:17:01 INFO - 'download-and-extract', 23:17:01 INFO - 'populate-webroot', 23:17:01 INFO - 'create-virtualenv', 23:17:01 INFO - 'install', 23:17:01 INFO - 'run-tests'), 23:17:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:17:01 INFO - 'download_minidump_stackwalk': True, 23:17:01 INFO - 'download_symbols': 'ondemand', 23:17:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:17:01 INFO - 'tooltool.py': '/tools/tooltool.py', 23:17:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:17:01 INFO - '/tools/misc-python/virtualenv.py')}, 23:17:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:17:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:17:01 INFO - 'installer_path': 'installer.exe', 23:17:01 INFO - 'log_level': 'info', 23:17:01 INFO - 'log_name': 'talos', 23:17:01 INFO - 'log_to_console': True, 23:17:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:17:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:17:01 INFO - 'opt_config_files': (), 23:17:01 INFO - 'pip_index': False, 23:17:01 INFO - 'sps_profile': False, 23:17:01 INFO - 'sps_profile_interval': 0, 23:17:01 INFO - 'suite': 'dromaeojs', 23:17:01 INFO - 'system_bits': '32', 23:17:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:17:01 INFO - 'title': 'talos-linux64-ix-042', 23:17:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:17:01 INFO - 'use_talos_json': True, 23:17:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:17:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:17:01 INFO - 'work_dir': 'build'} 23:17:01 INFO - [mozharness: 2016-10-17 06:17:01.411404Z] Running clobber step. 23:17:01 INFO - Running pre-action listener: _resource_record_pre_action 23:17:01 INFO - Running main action method: clobber 23:17:01 INFO - rmtree: /builds/slave/test/build 23:17:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:17:01 INFO - Running post-action listener: _resource_record_post_action 23:17:01 INFO - [mozharness: 2016-10-17 06:17:01.880384Z] Finished clobber step (success) 23:17:01 INFO - [mozharness: 2016-10-17 06:17:01.880486Z] Running read-buildbot-config step. 23:17:01 INFO - Running pre-action listener: _resource_record_pre_action 23:17:01 INFO - Running main action method: read_buildbot_config 23:17:01 INFO - Using buildbot properties: 23:17:01 INFO - { 23:17:01 INFO - "project": "", 23:17:01 INFO - "product": "firefox", 23:17:01 INFO - "script_repo_revision": "production", 23:17:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:17:01 INFO - "repository": "", 23:17:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:17:01 INFO - "buildid": "20161016201450", 23:17:01 INFO - "pgo_build": "True", 23:17:01 INFO - "basedir": "/builds/slave/test", 23:17:01 INFO - "buildnumber": 533, 23:17:01 INFO - "slavename": "talos-linux64-ix-042", 23:17:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:17:01 INFO - "platform": "ubuntu64_hw", 23:17:01 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:17:01 INFO - "branch": "fx-team", 23:17:01 INFO - "repo_path": "integration/fx-team", 23:17:01 INFO - "revision": "2976adccde9b75596c469428228177ed3d0f0b61", 23:17:01 INFO - "stage_platform": "linux64-pgo", 23:17:01 INFO - "builduid": "224945c9e2144f3e8f3a341954ac39aa", 23:17:01 INFO - "slavebuilddir": "test" 23:17:01 INFO - } 23:17:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 23:17:01 INFO - Running post-action listener: _resource_record_post_action 23:17:01 INFO - [mozharness: 2016-10-17 06:17:01.882985Z] Finished read-buildbot-config step (success) 23:17:01 INFO - [mozharness: 2016-10-17 06:17:01.883082Z] Running download-and-extract step. 23:17:01 INFO - Running pre-action listener: _resource_record_pre_action 23:17:01 INFO - Running main action method: download_and_extract 23:17:01 INFO - mkdir: /builds/slave/test/build/tests 23:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:01 INFO - trying https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 23:17:01 INFO - Downloading https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 23:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:17:02 INFO - Downloaded 1361 bytes. 23:17:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 23:17:02 INFO - Using the following test package requirements: 23:17:02 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:17:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:17:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'jsshell-linux-x86_64.zip'], 23:17:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:17:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:17:02 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:17:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:17:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:17:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 23:17:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:17:02 INFO - Fetch https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:17:03 INFO - Http code: 200 23:17:03 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 23:17:03 INFO - x-amz-cf-id: irVEPDeZBF_bKKACUVs3vqx_tQaIqN10VuMTZU3qZ7Et-SmJ2qaUZA== 23:17:03 INFO - x-amz-version-id: rBMXNwiYyRaB5TCdFUqe0IAu4TkRkofO 23:17:04 INFO - Expected file size: 22100321 23:17:04 INFO - Obtained file size: 22100321 23:17:08 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 23:17:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 23:17:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:17:08 INFO - Fetch https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:17:08 INFO - Http code: 200 23:17:08 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 23:17:08 INFO - x-amz-cf-id: NqD6L6TZCNMetGNBLCeaB7tSzfzOq-gMSVhQkVzku_EyZOcvkWhEAQ== 23:17:08 INFO - x-amz-version-id: rBMXNwiYyRaB5TCdFUqe0IAu4TkRkofO 23:17:09 INFO - Expected file size: 22100321 23:17:09 INFO - Obtained file size: 22100321 23:17:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:17:13 INFO - Fetch https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:17:14 INFO - Http code: 200 23:17:14 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 23:17:14 INFO - x-amz-cf-id: 6rFrlMo-pVuQLICOpQUiAii_D5itMTufRU539ap_fLbsMJHL3Gk3kQ== 23:17:14 INFO - x-amz-version-id: r45A7PgmQbuy2QSz2BSla_1b2UOd1.Mu 23:17:15 INFO - Expected file size: 14409006 23:17:15 INFO - Obtained file size: 14409006 23:17:15 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')]} 23:17:15 INFO - trying https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 23:17:15 INFO - Downloading https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 23:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:17:17 INFO - Downloaded 59721130 bytes. 23:17:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 23:17:17 INFO - mkdir: /builds/slave/test/properties 23:17:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:17:17 INFO - Writing to file /builds/slave/test/properties/build_url 23:17:17 INFO - Contents: 23:17:17 INFO - build_url:https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 23:17:18 INFO - Running post-action listener: _resource_record_post_action 23:17:18 INFO - Running post-action listener: set_extra_try_arguments 23:17:18 INFO - [mozharness: 2016-10-17 06:17:18.419124Z] Finished download-and-extract step (success) 23:17:18 INFO - [mozharness: 2016-10-17 06:17:18.419351Z] Running populate-webroot step. 23:17:18 INFO - Running pre-action listener: _resource_record_pre_action 23:17:18 INFO - Running main action method: populate_webroot 23:17:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:17:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:17:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:17:18 INFO - '--tppagecycles', 23:17:18 INFO - '7'], 23:17:18 INFO - 'tests': ['tsvgm']}}, 23:17:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:17:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:17:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:17:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:17:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['damp', 'tps']}, 23:17:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'tests': ['damp', 'tps']}, 23:17:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['dromaeo_dom']}, 23:17:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:17:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['basic_compositor_video']}, 23:17:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:17:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'other-e10s': {'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['a11yr', 23:17:18 INFO - 'ts_paint', 23:17:18 INFO - 'tpaint', 23:17:18 INFO - 'sessionrestore', 23:17:18 INFO - 'sessionrestore_no_auto_restore', 23:17:18 INFO - 'tabpaint']}, 23:17:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['tsvgx', 23:17:18 INFO - 'tsvgr_opacity', 23:17:18 INFO - 'tart', 23:17:18 INFO - 'tscrollx', 23:17:18 INFO - 'cart']}, 23:17:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:17:18 INFO - 'tsvgr_opacity', 23:17:18 INFO - 'tart', 23:17:18 INFO - 'tscrollx', 23:17:18 INFO - 'cart']}, 23:17:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'talos_options': ['--disable-e10s'], 23:17:18 INFO - 'tests': ['tp5o']}, 23:17:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'tests': ['tp5o']}, 23:17:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'talos_options': ['--disable-e10s', 23:17:18 INFO - '--xperf_path', 23:17:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:18 INFO - 'tests': ['tp5n']}, 23:17:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:18 INFO - 'talos_options': ['--xperf_path', 23:17:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:18 INFO - 'tests': ['tp5n']}}, 23:17:18 INFO - 'talos.zip': {'path': '', 23:17:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:17:18 INFO - Running post-action listener: _resource_record_post_action 23:17:18 INFO - [mozharness: 2016-10-17 06:17:18.435021Z] Finished populate-webroot step (success) 23:17:18 INFO - [mozharness: 2016-10-17 06:17:18.435110Z] Running create-virtualenv step. 23:17:18 INFO - Running pre-action listener: _resource_record_pre_action 23:17:18 INFO - Running main action method: create_virtualenv 23:17:18 INFO - Creating virtualenv /builds/slave/test/build/venv 23:17:18 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 23:17:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:17:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:17:18 INFO - Using real prefix '/usr' 23:17:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:17:19 INFO - Installing distribute.............................................................................................................................................................................................done. 23:17:21 INFO - Installing pip.................done. 23:17:21 INFO - Return code: 0 23:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:17:21 INFO - Reading from file tmpfile_stdout 23:17:21 INFO - Output received: 23:17:21 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) 23:17:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:17:21 INFO - Using env: {'DISPLAY': ':0', 23:17:21 INFO - 'HOME': '/home/cltbld', 23:17:21 INFO - 'LANG': 'en_US.UTF-8', 23:17:21 INFO - 'LANGUAGE': 'en_US:en', 23:17:21 INFO - 'LOGNAME': 'cltbld', 23:17:21 INFO - 'MAIL': '/var/mail/cltbld', 23:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:21 INFO - 'MOZ_NO_REMOTE': '1', 23:17:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:21 INFO - 'NO_EM_RESTART': '1', 23:17:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:21 INFO - 'PWD': '/builds/slave/test', 23:17:21 INFO - 'SHELL': '/bin/bash', 23:17:21 INFO - 'SHLVL': '1', 23:17:21 INFO - 'TERM': 'linux', 23:17:21 INFO - 'TMOUT': '86400', 23:17:21 INFO - 'USER': 'cltbld', 23:17:21 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:21 INFO - '_': '/tools/buildbot/bin/python'} 23:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:21 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 23:17:21 INFO - Cleaning up... 23:17:21 INFO - Return code: 0 23:17:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:17:21 INFO - Using env: {'DISPLAY': ':0', 23:17:21 INFO - 'HOME': '/home/cltbld', 23:17:21 INFO - 'LANG': 'en_US.UTF-8', 23:17:21 INFO - 'LANGUAGE': 'en_US:en', 23:17:21 INFO - 'LOGNAME': 'cltbld', 23:17:21 INFO - 'MAIL': '/var/mail/cltbld', 23:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:21 INFO - 'MOZ_NO_REMOTE': '1', 23:17:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:21 INFO - 'NO_EM_RESTART': '1', 23:17:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:21 INFO - 'PWD': '/builds/slave/test', 23:17:21 INFO - 'SHELL': '/bin/bash', 23:17:21 INFO - 'SHLVL': '1', 23:17:21 INFO - 'TERM': 'linux', 23:17:21 INFO - 'TMOUT': '86400', 23:17:21 INFO - 'USER': 'cltbld', 23:17:21 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:21 INFO - '_': '/tools/buildbot/bin/python'} 23:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:21 INFO - Downloading/unpacking psutil>=3.1.1 23:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:17:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:23 INFO - Installing collected packages: psutil 23:17:23 INFO - Running setup.py install for psutil 23:17:23 INFO - building 'psutil._psutil_linux' extension 23:17:23 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 23:17:23 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 23:17:23 INFO - building 'psutil._psutil_posix' extension 23:17:23 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 23:17:24 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 23:17:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:24 INFO - Successfully installed psutil 23:17:24 INFO - Cleaning up... 23:17:24 INFO - Return code: 0 23:17:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:17:24 INFO - Using env: {'DISPLAY': ':0', 23:17:24 INFO - 'HOME': '/home/cltbld', 23:17:24 INFO - 'LANG': 'en_US.UTF-8', 23:17:24 INFO - 'LANGUAGE': 'en_US:en', 23:17:24 INFO - 'LOGNAME': 'cltbld', 23:17:24 INFO - 'MAIL': '/var/mail/cltbld', 23:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:24 INFO - 'MOZ_NO_REMOTE': '1', 23:17:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:24 INFO - 'NO_EM_RESTART': '1', 23:17:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:24 INFO - 'PWD': '/builds/slave/test', 23:17:24 INFO - 'SHELL': '/bin/bash', 23:17:24 INFO - 'SHLVL': '1', 23:17:24 INFO - 'TERM': 'linux', 23:17:24 INFO - 'TMOUT': '86400', 23:17:24 INFO - 'USER': 'cltbld', 23:17:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:24 INFO - '_': '/tools/buildbot/bin/python'} 23:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:17:26 INFO - Installing collected packages: mozsystemmonitor 23:17:26 INFO - Running setup.py install for mozsystemmonitor 23:17:26 INFO - Successfully installed mozsystemmonitor 23:17:26 INFO - Cleaning up... 23:17:26 INFO - Return code: 0 23:17:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:17:26 INFO - Using env: {'DISPLAY': ':0', 23:17:26 INFO - 'HOME': '/home/cltbld', 23:17:26 INFO - 'LANG': 'en_US.UTF-8', 23:17:26 INFO - 'LANGUAGE': 'en_US:en', 23:17:26 INFO - 'LOGNAME': 'cltbld', 23:17:26 INFO - 'MAIL': '/var/mail/cltbld', 23:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:26 INFO - 'MOZ_NO_REMOTE': '1', 23:17:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:26 INFO - 'NO_EM_RESTART': '1', 23:17:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:26 INFO - 'PWD': '/builds/slave/test', 23:17:26 INFO - 'SHELL': '/bin/bash', 23:17:26 INFO - 'SHLVL': '1', 23:17:26 INFO - 'TERM': 'linux', 23:17:26 INFO - 'TMOUT': '86400', 23:17:26 INFO - 'USER': 'cltbld', 23:17:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:26 INFO - '_': '/tools/buildbot/bin/python'} 23:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:26 INFO - Downloading/unpacking jsonschema==2.5.1 23:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:17:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:29 INFO - Downloading functools32-3.2.3-2.tar.gz 23:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:17:29 INFO - warning: no files found matching '*.txt' 23:17:29 INFO - no previously-included directories found matching 'build' 23:17:29 INFO - no previously-included directories found matching 'dist' 23:17:29 INFO - no previously-included directories found matching '.git*' 23:17:29 INFO - Installing collected packages: jsonschema, functools32 23:17:29 INFO - Running setup.py install for functools32 23:17:29 INFO - warning: no files found matching '*.txt' 23:17:29 INFO - no previously-included directories found matching 'build' 23:17:29 INFO - no previously-included directories found matching 'dist' 23:17:29 INFO - no previously-included directories found matching '.git*' 23:17:29 INFO - Successfully installed jsonschema functools32 23:17:29 INFO - Cleaning up... 23:17:29 INFO - Return code: 0 23:17:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:17:29 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')]} 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:29 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')]} 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:29 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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:29 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 23:17:29 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 23:17:29 INFO - Using env: {'DISPLAY': ':0', 23:17:29 INFO - 'HOME': '/home/cltbld', 23:17:29 INFO - 'LANG': 'en_US.UTF-8', 23:17:29 INFO - 'LANGUAGE': 'en_US:en', 23:17:29 INFO - 'LOGNAME': 'cltbld', 23:17:29 INFO - 'MAIL': '/var/mail/cltbld', 23:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:29 INFO - 'MOZ_NO_REMOTE': '1', 23:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:29 INFO - 'NO_EM_RESTART': '1', 23:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:29 INFO - 'PWD': '/builds/slave/test', 23:17:29 INFO - 'SHELL': '/bin/bash', 23:17:29 INFO - 'SHLVL': '1', 23:17:29 INFO - 'TERM': 'linux', 23:17:29 INFO - 'TMOUT': '86400', 23:17:29 INFO - 'USER': 'cltbld', 23:17:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:29 INFO - '_': '/tools/buildbot/bin/python'} 23:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:17:29 INFO - Cleaning up... 23:17:29 INFO - Return code: 0 23:17:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:17:29 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')]} 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:29 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')]} 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:29 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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:29 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 23:17:29 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 23:17:29 INFO - Using env: {'DISPLAY': ':0', 23:17:29 INFO - 'HOME': '/home/cltbld', 23:17:29 INFO - 'LANG': 'en_US.UTF-8', 23:17:29 INFO - 'LANGUAGE': 'en_US:en', 23:17:29 INFO - 'LOGNAME': 'cltbld', 23:17:29 INFO - 'MAIL': '/var/mail/cltbld', 23:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:29 INFO - 'MOZ_NO_REMOTE': '1', 23:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:29 INFO - 'NO_EM_RESTART': '1', 23:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:29 INFO - 'PWD': '/builds/slave/test', 23:17:29 INFO - 'SHELL': '/bin/bash', 23:17:29 INFO - 'SHLVL': '1', 23:17:29 INFO - 'TERM': 'linux', 23:17:29 INFO - 'TMOUT': '86400', 23:17:29 INFO - 'USER': 'cltbld', 23:17:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:29 INFO - '_': '/tools/buildbot/bin/python'} 23:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:29 INFO - Downloading/unpacking blobuploader==1.2.4 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:31 INFO - Downloading blobuploader-1.2.4.tar.gz 23:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:17:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:17:31 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 23:17:31 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 23:17:31 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 23:17:31 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 23:17:31 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 23:17:31 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 23:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:17:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:17:32 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 23:17:32 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 23:17:32 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 23:17:32 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 23:17:32 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 23:17:32 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 23:17:32 INFO - Downloading docopt-0.6.1.tar.gz 23:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:17:32 INFO - Installing collected packages: blobuploader, requests, docopt 23:17:32 INFO - Running setup.py install for blobuploader 23:17:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:17:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:17:32 INFO - Running setup.py install for requests 23:17:33 INFO - Running setup.py install for docopt 23:17:33 INFO - Successfully installed blobuploader requests docopt 23:17:33 INFO - Cleaning up... 23:17:33 INFO - Return code: 0 23:17:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:33 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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:33 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 23:17:33 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 23:17:33 INFO - Using env: {'DISPLAY': ':0', 23:17:33 INFO - 'HOME': '/home/cltbld', 23:17:33 INFO - 'LANG': 'en_US.UTF-8', 23:17:33 INFO - 'LANGUAGE': 'en_US:en', 23:17:33 INFO - 'LOGNAME': 'cltbld', 23:17:33 INFO - 'MAIL': '/var/mail/cltbld', 23:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:33 INFO - 'MOZ_NO_REMOTE': '1', 23:17:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:33 INFO - 'NO_EM_RESTART': '1', 23:17:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:33 INFO - 'PWD': '/builds/slave/test', 23:17:33 INFO - 'SHELL': '/bin/bash', 23:17:33 INFO - 'SHLVL': '1', 23:17:33 INFO - 'TERM': 'linux', 23:17:33 INFO - 'TMOUT': '86400', 23:17:33 INFO - 'USER': 'cltbld', 23:17:33 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:33 INFO - '_': '/tools/buildbot/bin/python'} 23:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:33 INFO - Running setup.py (path:/tmp/pip-XDNW2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:33 INFO - Running setup.py (path:/tmp/pip-4P9Z2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:33 INFO - Running setup.py (path:/tmp/pip-uUKev4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:33 INFO - Running setup.py (path:/tmp/pip-dcHEEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:33 INFO - Running setup.py (path:/tmp/pip-DyCquL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:33 INFO - Running setup.py (path:/tmp/pip-41BLuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:33 INFO - Running setup.py (path:/tmp/pip-QSGKgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:33 INFO - Running setup.py (path:/tmp/pip-6m_w6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:34 INFO - Running setup.py (path:/tmp/pip-PkhRSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:34 INFO - Running setup.py (path:/tmp/pip-gooolD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:34 INFO - Running setup.py (path:/tmp/pip-jNkgA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:34 INFO - Running setup.py (path:/tmp/pip-pfEvzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:34 INFO - Running setup.py (path:/tmp/pip-Q5st39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:34 INFO - Running setup.py (path:/tmp/pip-2nt5lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:34 INFO - Running setup.py (path:/tmp/pip-nlUixl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:34 INFO - Running setup.py (path:/tmp/pip-ww90vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:34 INFO - Running setup.py (path:/tmp/pip-Xarhbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:17:34 INFO - Running setup.py install for manifestparser 23:17:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:17:34 INFO - Running setup.py install for mozcrash 23:17:34 INFO - Running setup.py install for mozdebug 23:17:35 INFO - Running setup.py install for mozdevice 23:17:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for mozfile 23:17:35 INFO - Running setup.py install for mozhttpd 23:17:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for mozinfo 23:17:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for mozInstall 23:17:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for mozleak 23:17:35 INFO - Running setup.py install for mozlog 23:17:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for moznetwork 23:17:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:17:35 INFO - Running setup.py install for mozprocess 23:17:35 INFO - Running setup.py install for mozprofile 23:17:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:17:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:17:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:17:36 INFO - Running setup.py install for mozrunner 23:17:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:17:36 INFO - Running setup.py install for mozscreenshot 23:17:36 INFO - Running setup.py install for moztest 23:17:36 INFO - Running setup.py install for mozversion 23:17:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:17:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:17:36 INFO - Cleaning up... 23:17:36 INFO - Return code: 0 23:17:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:36 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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:36 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 23:17:36 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 23:17:36 INFO - Using env: {'DISPLAY': ':0', 23:17:36 INFO - 'HOME': '/home/cltbld', 23:17:36 INFO - 'LANG': 'en_US.UTF-8', 23:17:36 INFO - 'LANGUAGE': 'en_US:en', 23:17:36 INFO - 'LOGNAME': 'cltbld', 23:17:36 INFO - 'MAIL': '/var/mail/cltbld', 23:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:36 INFO - 'MOZ_NO_REMOTE': '1', 23:17:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:36 INFO - 'NO_EM_RESTART': '1', 23:17:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:36 INFO - 'PWD': '/builds/slave/test', 23:17:36 INFO - 'SHELL': '/bin/bash', 23:17:36 INFO - 'SHLVL': '1', 23:17:36 INFO - 'TERM': 'linux', 23:17:36 INFO - 'TMOUT': '86400', 23:17:36 INFO - 'USER': 'cltbld', 23:17:36 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:36 INFO - '_': '/tools/buildbot/bin/python'} 23:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:36 INFO - Running setup.py (path:/tmp/pip-6TgK67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:36 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)) 23:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:36 INFO - Running setup.py (path:/tmp/pip-HScez6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:36 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)) 23:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:36 INFO - Running setup.py (path:/tmp/pip-sn4liR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:37 INFO - Running setup.py (path:/tmp/pip-WqsTLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:37 INFO - Running setup.py (path:/tmp/pip-EMN6ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:37 INFO - Running setup.py (path:/tmp/pip-KXpa09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:37 INFO - Running setup.py (path:/tmp/pip-CNxv6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:37 INFO - Running setup.py (path:/tmp/pip-PZ0qDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:37 INFO - Running setup.py (path:/tmp/pip-ambG2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:37 INFO - Running setup.py (path:/tmp/pip-3gw9Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:37 INFO - Running setup.py (path:/tmp/pip-xs3_ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:37 INFO - Running setup.py (path:/tmp/pip-8tYFRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:37 INFO - Running setup.py (path:/tmp/pip-bDTHH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:37 INFO - Running setup.py (path:/tmp/pip-i8z4fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:37 INFO - Running setup.py (path:/tmp/pip-AJs7_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:37 INFO - Running setup.py (path:/tmp/pip-yXYJQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:37 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)) 23:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:37 INFO - Running setup.py (path:/tmp/pip-62gnaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:38 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)) 23:17:38 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)) 23:17:38 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)) 23:17:38 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)) 23:17:38 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)) 23:17:38 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)) 23:17:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:39 INFO - Downloading blessings-1.6.tar.gz 23:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:17:40 INFO - Installing collected packages: blessings 23:17:40 INFO - Running setup.py install for blessings 23:17:40 INFO - Successfully installed blessings 23:17:40 INFO - Cleaning up... 23:17:40 INFO - Return code: 0 23:17:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:17:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:40 INFO - Reading from file tmpfile_stdout 23:17:40 INFO - Current package versions: 23:17:40 INFO - argparse == 1.2.1 23:17:40 INFO - blessings == 1.6 23:17:40 INFO - blobuploader == 1.2.4 23:17:40 INFO - docopt == 0.6.1 23:17:40 INFO - functools32 == 3.2.3-2 23:17:40 INFO - jsonschema == 2.5.1 23:17:40 INFO - manifestparser == 1.1 23:17:40 INFO - mozInstall == 1.12 23:17:40 INFO - mozcrash == 0.17 23:17:40 INFO - mozdebug == 0.1 23:17:40 INFO - mozdevice == 0.48 23:17:40 INFO - mozfile == 1.2 23:17:40 INFO - mozhttpd == 0.7 23:17:40 INFO - mozinfo == 0.9 23:17:40 INFO - mozleak == 0.1 23:17:40 INFO - mozlog == 3.4 23:17:40 INFO - moznetwork == 0.27 23:17:40 INFO - mozprocess == 0.23 23:17:40 INFO - mozprofile == 0.28 23:17:40 INFO - mozrunner == 6.12 23:17:40 INFO - mozscreenshot == 0.1 23:17:40 INFO - mozsystemmonitor == 0.3 23:17:40 INFO - moztest == 0.7 23:17:40 INFO - mozversion == 1.4 23:17:40 INFO - psutil == 3.1.1 23:17:40 INFO - requests == 1.2.3 23:17:40 INFO - wsgiref == 0.1.2 23:17:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:40 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 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17:40 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 23:17:40 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 23:17:40 INFO - Using env: {'DISPLAY': ':0', 23:17:40 INFO - 'HOME': '/home/cltbld', 23:17:40 INFO - 'LANG': 'en_US.UTF-8', 23:17:40 INFO - 'LANGUAGE': 'en_US:en', 23:17:40 INFO - 'LOGNAME': 'cltbld', 23:17:40 INFO - 'MAIL': '/var/mail/cltbld', 23:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:40 INFO - 'MOZ_NO_REMOTE': '1', 23:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:40 INFO - 'NO_EM_RESTART': '1', 23:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:40 INFO - 'PWD': '/builds/slave/test', 23:17:40 INFO - 'SHELL': '/bin/bash', 23:17:40 INFO - 'SHLVL': '1', 23:17:40 INFO - 'TERM': 'linux', 23:17:40 INFO - 'TMOUT': '86400', 23:17:40 INFO - 'USER': 'cltbld', 23:17:40 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:40 INFO - '_': '/tools/buildbot/bin/python'} 23:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 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)) 23:17:40 INFO - Cleaning up... 23:17:40 INFO - Return code: 0 23:17:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb3bbb0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf37b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, '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': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', '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 23:17: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', 'jsonschema'] in /builds/slave/test/build 23:17: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 jsonschema 23:17:40 INFO - Using env: {'DISPLAY': ':0', 23:17:40 INFO - 'HOME': '/home/cltbld', 23:17:40 INFO - 'LANG': 'en_US.UTF-8', 23:17:40 INFO - 'LANGUAGE': 'en_US:en', 23:17:40 INFO - 'LOGNAME': 'cltbld', 23:17:40 INFO - 'MAIL': '/var/mail/cltbld', 23:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:40 INFO - 'MOZ_NO_REMOTE': '1', 23:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:40 INFO - 'NO_EM_RESTART': '1', 23:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:40 INFO - 'PWD': '/builds/slave/test', 23:17:40 INFO - 'SHELL': '/bin/bash', 23:17:40 INFO - 'SHLVL': '1', 23:17:40 INFO - 'TERM': 'linux', 23:17:40 INFO - 'TMOUT': '86400', 23:17:40 INFO - 'USER': 'cltbld', 23:17:40 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:40 INFO - '_': '/tools/buildbot/bin/python'} 23:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:17:40 INFO - Cleaning up... 23:17:40 INFO - Return code: 0 23:17:40 INFO - Running post-action listener: _resource_record_post_action 23:17:40 INFO - Running post-action listener: _start_resource_monitoring 23:17:40 INFO - Starting resource monitoring. 23:17:40 INFO - [mozharness: 2016-10-17 06:17:40.939485Z] Finished create-virtualenv step (success) 23:17:40 INFO - [mozharness: 2016-10-17 06:17:40.939957Z] Running install step. 23:17:40 INFO - Running pre-action listener: _resource_record_pre_action 23:17:40 INFO - Running main action method: install 23:17:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:41 INFO - Reading from file tmpfile_stdout 23:17:41 INFO - Detecting whether we're running mozinstall >=1.0... 23:17:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:17:41 INFO - Reading from file tmpfile_stdout 23:17:41 INFO - Output received: 23:17:41 INFO - Usage: mozinstall [options] installer 23:17:41 INFO - Options: 23:17:41 INFO - -h, --help show this help message and exit 23:17:41 INFO - -d DEST, --destination=DEST 23:17:41 INFO - Directory to install application into. [default: 23:17:41 INFO - "/builds/slave/test"] 23:17:41 INFO - --app=APP Application being installed. [default: firefox] 23:17:41 INFO - mkdir: /builds/slave/test/build/application 23:17:41 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'] 23:17:41 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 23:17:58 INFO - Reading from file tmpfile_stdout 23:17:58 INFO - Output received: 23:17:58 INFO - /builds/slave/test/build/application/firefox/firefox 23:17:58 INFO - Running post-action listener: _resource_record_post_action 23:17:58 INFO - [mozharness: 2016-10-17 06:17:58.341751Z] Finished install step (success) 23:17:58 INFO - [mozharness: 2016-10-17 06:17:58.341997Z] Running run-tests step. 23:17:58 INFO - Running pre-action listener: _resource_record_pre_action 23:17:58 INFO - Running main action method: run_tests 23:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:17:58 INFO - Python 2.7.3 23:17:58 INFO - Return code: 0 23:17:58 INFO - grabbing minidump binary from tooltool 23:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:17:58 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 0x1c82790>, '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 0x1c83690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c83b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:17:58 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 23:17:58 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 23:17:58 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 23:17:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:17:58 INFO - Return code: 0 23:17:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:17:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:17:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:17:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:17:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 23:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 23:17:58 INFO - Using env: {'DISPLAY': ':0', 23:17:58 INFO - 'HOME': '/home/cltbld', 23:17:58 INFO - 'LANG': 'en_US.UTF-8', 23:17:58 INFO - 'LANGUAGE': 'en_US:en', 23:17:58 INFO - 'LOGNAME': 'cltbld', 23:17:58 INFO - 'MAIL': '/var/mail/cltbld', 23:17:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:17:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:58 INFO - 'MOZ_NO_REMOTE': '1', 23:17:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:58 INFO - 'NO_EM_RESTART': '1', 23:17:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:58 INFO - 'PWD': '/builds/slave/test', 23:17:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:17:58 INFO - 'SHELL': '/bin/bash', 23:17:58 INFO - 'SHLVL': '1', 23:17:58 INFO - 'TERM': 'linux', 23:17:58 INFO - 'TMOUT': '86400', 23:17:58 INFO - 'USER': 'cltbld', 23:17:58 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892', 23:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:58 INFO - '_': '/tools/buildbot/bin/python'} 23:17:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 23:17:58 INFO - mozversion application_buildid: 20161016201450 23:17:58 INFO - mozversion application_changeset: 2976adccde9b75596c469428228177ed3d0f0b61 23:17:58 INFO - mozversion application_display_name: Nightly 23:17:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:17:58 INFO - mozversion application_name: Firefox 23:17:58 INFO - mozversion application_remotingname: firefox 23:17:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:17:58 INFO - mozversion application_vendor: Mozilla 23:17:58 INFO - mozversion application_version: 52.0a1 23:17:58 INFO - mozversion platform_buildid: 20161016201450 23:17:58 INFO - mozversion platform_changeset: 2976adccde9b75596c469428228177ed3d0f0b61 23:17:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:17:58 INFO - mozversion platform_version: 52.0a1 23:17:58 INFO - using testdate: 1476685078 23:17:58 INFO - actual date: 1476685078 23:17:58 INFO - starting webserver on 'localhost:33969' 23:17:58 INFO - SUITE-START | Running 2 tests 23:17:58 INFO - TEST-START | dromaeo_css 23:17:58 INFO - operating with platform_type : linux_ 23:17:58 INFO - Initialising browser for dromaeo_css test... 23:17:58 INFO - TEST-INFO | started process 13423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR5cIDi/profile http://localhost:33969/getInfo.html) 23:18:03 INFO - PROCESS | 13423 | __metrics Screen width/height:1600/1200 23:18:03 INFO - PROCESS | 13423 | colorDepth:24 23:18:03 INFO - PROCESS | 13423 | Browser inner width/height: 1024/697 23:18:03 INFO - PROCESS | 13423 | __metrics 23:18:03 INFO - PROCESS | 13423 | 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" 23:18:03 INFO - PROCESS | 13423 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:18:04 INFO - PROCESS | 13423 | console.error: PushService: 23:18:04 INFO - PROCESS | 13423 | stateChangeProcessEnqueue: Error transitioning state 23:18:04 INFO - PROCESS | 13423 | UnknownError 23:18:04 INFO - TEST-INFO | 13423: exit 0 23:18:04 INFO - Browser initialized. 23:18:04 INFO - Running cycle 1/1 for dromaeo_css test... 23:18:04 INFO - TEST-INFO | started process 13505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR5cIDi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:16 INFO - PROCESS | 13505 | RSS: Main: 172990464 23:18:16 INFO - PROCESS | 13505 | 23:19:08 INFO - PROCESS | 13505 | 1476685148317 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] 23:21:18 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33969/tests/dromaeo/cssquery-ext.html) 23:21:19 INFO - PROCESS | 13505 | RSS: Main: 283082752 23:21:19 INFO - PROCESS | 13505 | 23:24:22 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-ext.html (next: http://localhost:33969/tests/dromaeo/cssquery-jquery.html) 23:24:22 INFO - PROCESS | 13505 | RSS: Main: 274292736 23:24:22 INFO - PROCESS | 13505 | 23:27:24 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33969/tests/dromaeo/cssquery-mootools.html) 23:27:25 INFO - PROCESS | 13505 | RSS: Main: 220917760 23:27:25 INFO - PROCESS | 13505 | 23:30:27 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33969/tests/dromaeo/cssquery-prototype.html) 23:30:27 INFO - PROCESS | 13505 | RSS: Main: 248864768 23:30:27 INFO - PROCESS | 13505 | 23:33:30 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33969/tests/dromaeo/cssquery-yui.html) 23:33:31 INFO - PROCESS | 13505 | RSS: Main: 215363584 23:33:31 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-yui.html (next: http://localhost:33969/tests/dromaeo/cssquery-dojo.html) 23:36:34 INFO - PROCESS | 13505 | RSS: Main: 307896320 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | __start_tp_report 23:36:34 INFO - PROCESS | 13505 | _x_x_mozilla_page_load 23:36:34 INFO - PROCESS | 13505 | _x_x_mozilla_page_load_details 23:36:34 INFO - PROCESS | 13505 | |i|pagename|runs| 23:36:34 INFO - PROCESS | 13505 | |0;dojo.html;2942.161483977067;2991.685872983338;2994.5807586937826;3003.6846131156212;3038.0733876167774;5046.646734728562;5139.897202055957;5151.81968631098;5182.937497812949;5211.426743058265;5145.022445735308;5198;5206.375234971809;5229.3201883755155;5302.893942121155;4659.767011649421;4725.787339569711;4730.172219861528;4787.090452813963;4834.081524510332;4755.9286610700865;4803.279508073782;4828.686135401188;4845.394325709286;4873.366462359906;4817.181079216542;4822.300766388871;4840.273958906157;4851.805927762885;4905.730184839832;4637.2116740153915;4666.53334666534;4718.8584342469785;4764.595009424203;4807.639427042951;4706.082313948781;4745.762711864428;4764.380630518045;4805.063012712522;4840.273958906192;5144.5369916707305;5188.273641690166;5198.350206224222;5203.323567936144;5225.634205605571;5389.380221274564;5412.621116521845;5431.646942948735;5548.306461692288;5554.694491802949;5499.972500137514;5530.308711411074;5542.584306177053;5602.803901863456;5605.439456054402;5146.459621739701;5154.742262886841;5156.123459011977;5157.329547158883;5224.37307523092;5502.477264659851;5550.250716153268;5594.160875868571;5614.747336369873;5626.409227031185;5350.892982140335;5410.269613602192;5412.783488660488;5413.404525502192;5416.620836541404;5480.7533660985355;5527.751251193706;5537.833864984057;5558.166275058693;5593.524550413179;5530.695811730313;5535.308086489189;5535.391106978229;5543.944560554423;5595.132754423071;5500.614956952975;5553.722313884289;5561.944380556223;5563.944360556424;5607.943920560824;4244.448221731217;4287.614114729689;4304.634106100953;4326.956730432718;4342.6742994275555;3852.788096654655;3903.8048097595006;3904.7657140571655;3921.11774850662;3928.332183528807;5316.149416093406;5323.414424413311;5325.818598967524;5328.706921119376;5389.218563308298;5386.973065134727;5534.529564987021;5564.4991950724625;5570.080936645408;5581.804636837691;5181.35233095863;5215.5827533797965;5231.5030072143845;5269.314989051399;5364.91952620711;4775.904481910342;4784.210605250095;4794.065157294294;4794.520547945178;4842.467328593852;5750.30996280449;5758.942410575841;5759.366469688247;5760.0783874579865;5763.193152958589;5387.676739395649;5442;5449.754761035683;5501.3948465667945;5507.917381239365;5726.484616384545;5730.054541000688;5731.713414329183;5737.282839645044;5785.247917770663;5384.111621582395;5424.83725488236;5429.701366424884;5430.239766432782;5432.348118225838;5392;5487.067198576172;5493.203485494501;5529.30883639545;5600.383957764724;5357.249985002025;5362.812301569426;5383.434739352311;5411.269478620336;5427.918581221206;5390.730463476732;5400.702961337164;5407.026735187546;5409.486098820606;5471.480209380103;5243.842684719464;5305;5322.707251101072;5386.165144402624;5399.163129714901;5451.4276001019325;5454.290942177491;5457.372402173801;5500.092484740133;5522.088855338824;5303.761330740049;5338.46615338463;5341.305630268041;5350.30446042012;5408.972955135199 23:36:34 INFO - PROCESS | 13505 | |1;ext.html;565.010816203562;581.5986968991396;602.0397466041665;612.0635427795473;617.4720613875133;30620.846895765517;31334.373312533735;34323.14192145191;34339;34355.31289374211;34739.65260347396;42256;43204;43337.783311083476;48580.757096214555;8754.912450875474;8854.29165666745;8856.778580535505;8863.645454181826;8895.066018068106;7982.560959147245;8176.1006289308125;8731.781705457382;8831;8832;10497.737556561071;10732.19508536859;10736.570537178504;11091.33451992879;11107.166962477806;10699.625513107043;10766.192535559787;10775.515101820358;10776.084032857216;10778.353298802018;7731.304182623591;7940.166282540308;10165.4917254137;10548.261621686484;10619;41003.6946120656;41387.1722565547;41423.792881035704;41462.585374146176;41466.79266603678;3470.8438120284395;3494.7204223662047;3514.297140571896;3582.8208589570418;3734.2158146789207;29370.11889643314;30210.69789302101;31669.20826979321;31943.361132777445;32872;4358.302671572533;4461.219286624828;4514.41369987379;4522.638188944877;4526.144280844838;330203;332052;332849;333411;334473;57682.13476797851;58501.414985849595;58616.70691646515;58617;58653.413465864796;61564.69217653883;61606.69196653988;61720;61772.837950671514;61840.69079654663;15013.174275414738;15559.14424706652;15666.295016724274;15671.843281567266;15681.529554113395;12483.251004939551;12602.684932876751;12615.621531354074;12618.432170552347;12622.242665440102;16298.755518667229;16605.91697041507;17840.28638854431;18039.91182159786;18356.724649130272;6909.447244220551;6967.372936435745;7131.286871312931;7182.784516464514;7321.670524826395;947.6020071569957;1066.7546464313252;1071.9088877445504;1075.3010543147018;1122.2817396866012;31208.687913121033;31902.361952760813;32046.35907281888;32564.185895352806;32690.346193076006;1067.685032915292;1132.049078773834;1173.0732721150366;1188.578054790537;1200.3578085724096;16515.752263716055;17012.48962531126;17054.48836534881;17058.57353566146;17227.818031234165;525554;530944;531064;531834;532032;46998.29502557465;48236;52707.209391859156;54549;54708;1283.8395200599925;1321.669582604349;1353.1137105196112;1353.6345186799508;1361.251311778518;29987.846920806394;32563.348733024734;32852;33327.83336083304;33334.66665333413;49666.255006174215;50723.492765071875;50734.49265507298;50735.23897141472;50775;344591;345164;345604;346592;346734;28054.2986425341;28105.859470702515;28111.43777124487;28422.715772842836;28530.286742831595;45996;46405;47513.52486475091;48072;48157.5184248153;39325.60674393334;39869;40239.396409053305;40894.38658420066;42270.154596907276;39746;39759.71428713772;39936.80031599823;40002.79998599988;40833;41332.58667413287;41940.16119677528;42211.36682949697;42275.36586951137;42342;33909.66090339164;35303;35732;35826.641733583376;36315.45526817047;27072;27545.724542755117;27607.171784846487;27734.861325693244;27778.305542360795 23:36:34 INFO - PROCESS | 13505 | |2;jquery.html;372.57898574610687;389.7875657766517;404.3389058888717;405.36965019394836;422.16200841329953;12934.288614126232;15016.324265408055;18457.169427375768;18982.71525927112;19334.903325483352;18720.251189952385;18932.337368192115;19263.32578359758;19549.609007819836;19572.510687232825;4962.627802914778;5554.9722251388785;5789.594728369016;5794.855128621776;5795.536357091443;5232.581393488512;5590.804321848736;5688.60179787415;5712.942870571283;5725.7995970141055;5460.399356070829;5608.270924779773;5612.69130197841;5618.943810561883;5623.100303951369;5649.689267090308;5804;5812.970935145339;5814.302283725938;5820.505257053155;5486.61593688442;5609.1305847593685;5639.943600563983;5645.717714114319;5661.971690141523;11757.647270581896;11892.345920974343;11901.464434100486;11909.940450297692;12632.431540580605;2208.293346129239;2331.4521087544413;2717.904873329424;2939.2357986923553;3008.6239220097486;9598.856017159747;10228.488575571191;10232.693019209435;10239.641612543524;11154;1762.0837164674426;1769.7433872088604;1771.5748220427133;1810.2306519729063;1849.9630007399912;77114;81052;82506.58746706229;84352.73470898003;85094.14905850987;10566.788664226673;10775.353478791298;11087.445627718582;11098;11685.377532964809;15470.922645386701;15473.613159671098;15493.457728979656;15498;15498.14760188178;7800.219978002267;7817.5309481431295;7976.643970525025;8016.759497215093;8150.3479721622125;7243.963780181065;7307.26927307265;7450.664720087609;7508.662110205054;7536.284053014955;4697.637685071291;4704.95295047052;4766.356541866873;4789.880252993703;4898.167311557044;1884.8115188481042;1998.15078591598;2024.2004408258656;2025.0887100804696;2066.597013582337;827.561392461984;927.4342650982895;969.6896992962326;981.3228872078244;1078.9028987391173;18634;19140;19338.90330548338;19405.223791048484;19483.790997331078;966.2173639352147;973.9951300243595;977.2279898879948;980.4313498171043;993.1210878372548;9937.493483732904;10652.786944261072;10755.892441075646;10770.730731731614;10782.137429005661;393899;398849;410890;411585;412535;83864;85820.57089714636;86027;87435;88944.5552772232;998.5620706183073;1005.1908213887745;1013.8073765984586;1015.1016350529834;1018.3533456255183;16009.599760006093;16377.181140943143;16778.58053548671;16995.49013529596;17293;40642;40835.79582102071;41414.58585414107;41741;42905;53434;54030;54309;54422.18366724423;55958.44041559532;5643.971780141073;6548.116004339544;8011.399145064027;8274.958625207077;8360.581970901552;11351.886481135083;12591.811122833347;12961.935190323988;13233.867661323264;13400.798988014993;5666.9716651416475;5730.770769169187;9655.565499552678;10235.130013948898;10578.153747700011;5417.268668729828;9215.49314787693;9650.324477286522;9906.653267135616;10774.084202842847;10772;10974.780504390028;11069;11689.649310520357;11817.172797904064;7980.521168729895;8073.677052918052;8195.869074040911;8430.156984301766;8787.472751634923;9536.856947145938;10696.83954740664;11057.391843448688;11776.411179441166;11797.174197806415 23:36:34 INFO - PROCESS | 13505 | |3;mootools.html;1336.3251557963226;1473.145575566172;1482.3033174408026;1650.6616143690544;1773.911304434778;7673;8411.242988131075;8646.178613031767;8704.521251331174;9023.684171054014;8781.692640757576;8856.468611883276;8863.512506812123;8864.246539044188;8866.645334186642;2294.9541009179766;2313.1610369756;2319.69843920291;2327.034280773477;2327.476317828492;2271.3980795089315;2288.6910267113894;2313.8430784607694;2328.6157783965627;2343.414146463384;2279.2022792022826;2290.0725206291477;2303.7005189325364;2329.848559843613;2339.3917581428864;2125.2455378340696;2127.563849410867;2136.8824714640687;2145.034734369532;2198.5273166269167;2134.8398870084807;2158.2446143849684;2166.4800447892394;2169;2175.401764514771;8866.556672166365;8897.065808090121;8901.154390332907;8905.910940890573;8920.330975176823;5051.722155281458;5273.367195936474;5273.6572122811895;5312.3093997780425;5317.229001794718;3288.177955511122;3390.135515443571;3483.5819701635714;3497.9475307870403;3501.369753444379;1171.2855158353398;1217.1419149499582;1229.0474881966459;1243.9315837628926;1245.7321675839737;12453.75092498154;12515.624531263986;12570.805773451508;12571.93714031433;12586.370681465982;4255.3829694694095;4460.375547423363;4546.431696037996;4609.746463944515;4615.838445654386;9707.369021013752;9707.514624268759;9709.6116155353;9714.368566043326;9754.0733630305;5130.4356520782685;5131.897362052738;5136.049830781318;5140.691558506502;5140.948590514122;4253.234417804763;4398.0324328648285;4563.292689633112;4675.158471475167;4755.358026666425;3632.54593175853;3729.664330210294;3775.9244815104094;3789.147441825567;3843.135294558758;1077.7305673581604;1084.5499117866154;1087.6736978906297;1089.2048804372694;1089.5042755546208;854.5513605357237;874.4753148111082;890.4612709310903;891.1311471315545;907.3467103685336;5394.217838413408;5465.344158700902;5549.223108764776;5696.060150075274;6000.609960352563;935.3966691483955;1010.3988127064385;1018.6180182431589;1027.99486002571;1036.7356324137297;3835.53973523179;3852.749571277858;3874.205787813548;3917.5690674025836;3920.5883382245024;10767.3539587625;11133.498992545356;11139.443027848574;11142.832857507145;11149.108071354272;11087.667369978913;11090.057345125593;11101.777964440666;11106.777864442665;11202.495887685074;8265.173482651686;8296.128906464734;8345.66617335312;8476.279516241066;8491.448055876348;5717.5997680163655;5762.4525670061275;5781.566382521243;5833.358330583717;5951.434613711691;10159.288849780445;10209;10244.846327305246;10273.691789246335;10457.320274182153;10170.694879153638;10450.790984180427;10725.249232553646;10729.624463143753;10730.248882578144;6824.727010919508;7060.329268719669;7237;7470;7606.467547271638;8626.56867156627;8760.342974276828;8790.780230494289;8796.648134074565;8801.867971980297;7749.108852482037;7774.105977812624;7937.960310198412;7940.841183176189;8037.31682806968;7624.199459056719;7644.0827100748265;7731.265529774662;7759.146493885781;7780.2219778021745;6854.143232095988;6938.444924406136;6945.583265004116;6948.687309071003;6956.40870526011;4756.476787553297;4885;4905.877353066202;4978.726170060681;5034.244863270539;5010.549050585465;5117.4114976776655;5177.741112944413;5217.582593392595;5261.842144735664 23:36:34 INFO - PROCESS | 13505 | |4;prototype.html;318.1854452601342;324.33025801719447;329.2542391483291;329.47448819133473;332.85687154523544;5103.923441148386;5192.896142077157;5256.658317209383;5290.603204759644;5294;5154.8453546393575;5237.423883372836;5247.396549396821;5282.867928301785;5290.444503327153;4132.338825787867;4139.867967996484;4142.958570414303;4145.2952997990415;4222.59885310895;3963.8414463421573;4106.3635136553885;4180.958190418088;4203.978980105096;4229.344451610005;4085.46888904442;4086.5300490443533;4091.2226676931436;4096.795160241976;4097.9180416391655;4072.7149099563044;4076.9184616307803;4081.571434999312;4087;4119.938200926988;3602.2975519773654;3622.836972336251;3641.5083963665097;3642.7814331140216;3646.1431563582537;5087.821926232564;5088.949110508885;5098.5411312982005;5119.872003199913;5144.305518754958;4643.698159619614;4681.391419115527;4681.806139434457;4687.742174180418;4688.734041808727;5481.972590137024;5486.91769623456;5490.450954904518;5525.889482210374;5566.970110529565;3787.734858559902;3914.4715463412645;3918.275119102975;3918.666913312372;3926.725129240954;5664.26364572603;5671.546276297886;5688.175214593902;5706.201131841545;5723.198752174699;4021.819018144132;4031.5565287818317;4049.1091959768805;4053.6959728020515;4056.2698714231133;3986.920261594752;4071.75569465833;4081.7346872453195;4092.795360232036;4099.8975025624595;4637.304404339376;4644.419447569054;4647.837325693585;4654.883627909329;4659.2312268475325;5530.723463826793;5581.358143813432;5588.91616625751;5621.747021384048;5625.846701426198;1329.1692692067459;1334.7424455925932;1344.28080976677;1396.7834985577251;1403.7192561487743;872.9432586881833;890.7416849113814;901.2519608724742;901.9188273055456;918.522368368458;4752.714837109785;4756.548127927841;4784.277660042382;4792.616590672807;4826.0589185108565;4933.804457016593;4959.156943319645;4974.104661160954;4990.700557966534;4999.000399840093;4535.4103966484145;4692.6245900327895;4706.505816889246;4732.95267047332;4739.66822322441;2576.175623800366;2584.8368234294644;2618.865624516013;2659.8005149613855;2664.760886187932;5071.884185479189;5115;5127.53852153308;5148.922766158511;5151.742412879341;5001.924586213909;5005.447221916776;5131.332316996991;5173.870653233699;5319.414864364917;5815.127730840408;5841.299044114733;5897.882042359129;5908.881822363529;5921.733521991435;5687.090065589486;5698.401667824819;5700.315962084576;5709.600327977002;5754.2519472469985;5554.361248456317;5595.748191331481;5646.322441307069;5660;5729.54163666914;5853.2390789198835;5859.94140058611;5878.647281163144;5882.764689412375;5885.176075349369;4080.367543030716;4140.23405669952;4146.730462519927;4148.315527937856;4160.937585936153;5358.196270559291;5428.724249801373;5538.750756215899;5591.6365436246515;5619;5345.1447768356875;5392.595555333287;5469.535089517435;5471.781128754806;5490.91763623538;5478.205660179331;5478.370382216638;5498.086014412368;5527.447255274601;5537.307836520435;5333.839984800462;5496.31296087989;5548.250986116984;5549.084401073777;5552.333719953632;5537.501624853782;5539.25220095282;5543.77824887;5559.24950131727;5560.7219639018695;5413.9729301353245;5433.619646624698;5439.014759612792;5486.506214440719;5492.231087647652 23:36:34 INFO - PROCESS | 13505 | |5;yui.html;737.1854101218154;751.7406494759313;753.6495529578741;793.7539362797534;835.6740871060288;14476.420943162275;15063.472778452757;15070.095794252353;15079;15105;14357.641058973531;14394.352254148562;14475.493357732481;14489.710205795878;14515.201663908481;792.7225471085103;812.1999830167281;814.7392834293029;814.8125931035866;815.6981916690833;647.258888572584;667.0627767985987;794.8608993426157;814.5153633588035;825.4634487583089;849.6346570974479;854.8333075050336;857.4041041476177;858.9527575983318;859.7463748194298;850;851.4720873058712;855.6278019061729;856.6573370651759;857.0272740439584;503.21247248056676;519.5557798082623;522.5010115339842;523.5837509180172;610.7343305662007;13999.65000874976;14510.492132775407;14518.346674399572;14519.564413067625;14529.346179421951;14142.717145657029;14630.341634626468;14673.62506623472;14773.261336933218;14914.62713432162;12610.432530536147;12851.742965140738;12881.227126372447;13184.143030702973;13265.734685306335;4328.826846926119;4385.780710964471;4403.3174857897075;4421.446396412061;4458.264386376244;76065;76631.23368766352;77319;77434;77485;11478.88521114778;11553.711157221136;11587.942060289644;11731.178817482865;11821.408929553489;22502;22511.212107576157;22545;22546.77453225447;22618.0952761891;8311.376646751518;8565.828683426296;8691.95654021726;8701.608427620773;8895.066018068139;4706;4712.552307530779;4746.5253474652945;4789.233722604366;4916.0905232532095;3501.474778783203;3605;3608.278344331144;3613.385724426854;3628.8367023484006;604.3684349854412;606.3390903914666;613.8434699151777;623.0871772852793;629.8488362792988;602.3344204653882;616.2081724983375;681.3799442507394;700.6496751624188;711.8006958051753;9093.227075698507;9154.359194856295;9402.764930876783;9459.52702364879;9676.177524910461;600.2976517474565;658.1674182159572;670.4703284405265;686.5331574529368;699.6851416862432;4158.2307273154565;4552.855184563859;4693.906121877543;4734.597559207506;4823.589994850407;114557;119146.40426797984;120857;124425.37787311187;124511;61009;93542.53228733949;102295;102379.48810255901;103950.48024759827;73389;74049;74528;84979;86131;8925.241354484942;9330.582301696653;9803.852942205729;9808.166305863797;9993.750156246151;49174.50825491842;49808;51304.23043654273;51482.48517514927;51642;66312;72966;73939;74138;77061;12620.55828046014;12752.48990040388;12828.230306181285;12941.935290323489;13320.666983325495;13880.652983675489;14139.929300353433;14587.343569539184;14776.852231477547;15360.23198840076;13499.86500134986;13585.116967397087;13707.314634268447;14234.359453824396;14624.85375146235;13442.798358024833;13605.319734013457;13716.862831371558;13847.238401887993;14143.363548640542;12996.285204313854;13130.73738525205;13332.466701331838;13711.862881371058;14219.146851188962;10095.646652367132;10100.494975251355;10101.949490252502;10417.374957502574;10653.520591573553;10282.370796389596;11386.146039046938;11622.6513204604;11864.169508134348;11966.162368634112 23:36:34 INFO - PROCESS | 13505 | __end_tp_report 23:36:34 INFO - PROCESS | 13505 | __start_cc_report 23:36:34 INFO - PROCESS | 13505 | _x_x_mozilla_cycle_collect,986 23:36:34 INFO - PROCESS | 13505 | __end_cc_report 23:36:34 INFO - PROCESS | 13505 | __startTimestamp1476686194428__endTimestamp 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | ------- Summary: start ------- 23:36:34 INFO - PROCESS | 13505 | Number of tests: 6 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#0] dojo.html Cycles:165 Average:5158.49 Median:5362.81 stddev:560.29 (10.4%) stddev-sans-first:534.35 23:36:34 INFO - PROCESS | 13505 | Values: 2942.2 2991.7 2994.6 3003.7 3038.1 5046.6 5139.9 5151.8 5182.9 5211.4 5145.0 5198.0 5206.4 5229.3 5302.9 4659.8 4725.8 4730.2 4787.1 4834.1 4755.9 4803.3 4828.7 4845.4 4873.4 4817.2 4822.3 4840.3 4851.8 4905.7 4637.2 4666.5 4718.9 4764.6 4807.6 4706.1 4745.8 4764.4 4805.1 4840.3 5144.5 5188.3 5198.4 5203.3 5225.6 5389.4 5412.6 5431.6 5548.3 5554.7 5500.0 5530.3 5542.6 5602.8 5605.4 5146.5 5154.7 5156.1 5157.3 5224.4 5502.5 5550.3 5594.2 5614.7 5626.4 5350.9 5410.3 5412.8 5413.4 5416.6 5480.8 5527.8 5537.8 5558.2 5593.5 5530.7 5535.3 5535.4 5543.9 5595.1 5500.6 5553.7 5561.9 5563.9 5607.9 4244.4 4287.6 4304.6 4327.0 4342.7 3852.8 3903.8 3904.8 3921.1 3928.3 5316.1 5323.4 5325.8 5328.7 5389.2 5387.0 5534.5 5564.5 5570.1 5581.8 5181.4 5215.6 5231.5 5269.3 5364.9 4775.9 4784.2 4794.1 4794.5 4842.5 5750.3 5758.9 5759.4 5760.1 5763.2 5387.7 5442.0 5449.8 5501.4 5507.9 5726.5 5730.1 5731.7 5737.3 5785.2 5384.1 5424.8 5429.7 5430.2 5432.3 5392.0 5487.1 5493.2 5529.3 5600.4 5357.2 5362.8 5383.4 5411.3 5427.9 5390.7 5400.7 5407.0 5409.5 5471.5 5243.8 5305.0 5322.7 5386.2 5399.2 5451.4 5454.3 5457.4 5500.1 5522.1 5303.8 5338.5 5341.3 5350.3 5409.0 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#1] ext.html Cycles:180 Average:56509.65 Median:3504.51 stddev:109356.12 (3120.4%) stddev-sans-first:109582.22 23:36:34 INFO - PROCESS | 13505 | Values: 565.0 581.6 602.0 612.1 617.5 30620.8 31334.4 34323.1 34339.0 34355.3 34739.7 42256.0 43204.0 43337.8 48580.8 8754.9 8854.3 8856.8 8863.6 8895.1 7982.6 8176.1 8731.8 8831.0 8832.0 10497.7 10732.2 10736.6 11091.3 11107.2 10699.6 10766.2 10775.5 10776.1 10778.4 7731.3 7940.2 10165.5 10548.3 10619.0 41003.7 41387.2 41423.8 41462.6 41466.8 3470.8 3494.7 3514.3 3582.8 3734.2 29370.1 30210.7 31669.2 31943.4 32872.0 4358.3 4461.2 4514.4 4522.6 4526.1 330203.0 332052.0 332849.0 333411.0 334473.0 57682.1 58501.4 58616.7 58617.0 58653.4 61564.7 61606.7 61720.0 61772.8 61840.7 15013.2 15559.1 15666.3 15671.8 15681.5 12483.3 12602.7 12615.6 12618.4 12622.2 16298.8 16605.9 17840.3 18039.9 18356.7 6909.4 6967.4 7131.3 7182.8 7321.7 947.6 1066.8 1071.9 1075.3 1122.3 31208.7 31902.4 32046.4 32564.2 32690.3 1067.7 1132.0 1173.1 1188.6 1200.4 16515.8 17012.5 17054.5 17058.6 17227.8 525554.0 530944.0 531064.0 531834.0 532032.0 46998.3 48236.0 52707.2 54549.0 54708.0 1283.8 1321.7 1353.1 1353.6 1361.3 29987.8 32563.3 32852.0 33327.8 33334.7 49666.3 50723.5 50734.5 50735.2 50775.0 344591.0 345164.0 345604.0 346592.0 346734.0 28054.3 28105.9 28111.4 28422.7 28530.3 45996.0 46405.0 47513.5 48072.0 48157.5 39325.6 39869.0 40239.4 40894.4 42270.2 39746.0 39759.7 39936.8 40002.8 40833.0 41332.6 41940.2 42211.4 42275.4 42342.0 33909.7 35303.0 35732.0 35826.6 36315.5 27072.0 27545.7 27607.2 27734.9 27778.3 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#2] jquery.html Cycles:180 Average:25761.72 Median:226974.79 stddev:67389.12 (29.7%) stddev-sans-first:67551.21 23:36:34 INFO - PROCESS | 13505 | Values: 372.6 389.8 404.3 405.4 422.2 12934.3 15016.3 18457.2 18982.7 19334.9 18720.3 18932.3 19263.3 19549.6 19572.5 4962.6 5555.0 5789.6 5794.9 5795.5 5232.6 5590.8 5688.6 5712.9 5725.8 5460.4 5608.3 5612.7 5618.9 5623.1 5649.7 5804.0 5813.0 5814.3 5820.5 5486.6 5609.1 5639.9 5645.7 5662.0 11757.6 11892.3 11901.5 11909.9 12632.4 2208.3 2331.5 2717.9 2939.2 3008.6 9598.9 10228.5 10232.7 10239.6 11154.0 1762.1 1769.7 1771.6 1810.2 1850.0 77114.0 81052.0 82506.6 84352.7 85094.1 10566.8 10775.4 11087.4 11098.0 11685.4 15470.9 15473.6 15493.5 15498.0 15498.1 7800.2 7817.5 7976.6 8016.8 8150.3 7244.0 7307.3 7450.7 7508.7 7536.3 4697.6 4705.0 4766.4 4789.9 4898.2 1884.8 1998.2 2024.2 2025.1 2066.6 827.6 927.4 969.7 981.3 1078.9 18634.0 19140.0 19338.9 19405.2 19483.8 966.2 974.0 977.2 980.4 993.1 9937.5 10652.8 10755.9 10770.7 10782.1 393899.0 398849.0 410890.0 411585.0 412535.0 83864.0 85820.6 86027.0 87435.0 88944.6 998.6 1005.2 1013.8 1015.1 1018.4 16009.6 16377.2 16778.6 16995.5 17293.0 40642.0 40835.8 41414.6 41741.0 42905.0 53434.0 54030.0 54309.0 54422.2 55958.4 5644.0 6548.1 8011.4 8275.0 8360.6 11351.9 12591.8 12961.9 13233.9 13400.8 5667.0 5730.8 9655.6 10235.1 10578.2 5417.3 9215.5 9650.3 9906.7 10774.1 10772.0 10974.8 11069.0 11689.6 11817.2 7980.5 8073.7 8195.9 8430.2 8787.5 9536.9 10696.8 11057.4 11776.4 11797.2 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#3] mootools.html Cycles:180 Average:5759.97 Median:4612.79 stddev:3355.65 (72.7%) stddev-sans-first:3348.59 23:36:34 INFO - PROCESS | 13505 | Values: 1336.3 1473.1 1482.3 1650.7 1773.9 7673.0 8411.2 8646.2 8704.5 9023.7 8781.7 8856.5 8863.5 8864.2 8866.6 2295.0 2313.2 2319.7 2327.0 2327.5 2271.4 2288.7 2313.8 2328.6 2343.4 2279.2 2290.1 2303.7 2329.8 2339.4 2125.2 2127.6 2136.9 2145.0 2198.5 2134.8 2158.2 2166.5 2169.0 2175.4 8866.6 8897.1 8901.2 8905.9 8920.3 5051.7 5273.4 5273.7 5312.3 5317.2 3288.2 3390.1 3483.6 3497.9 3501.4 1171.3 1217.1 1229.0 1243.9 1245.7 12453.8 12515.6 12570.8 12571.9 12586.4 4255.4 4460.4 4546.4 4609.7 4615.8 9707.4 9707.5 9709.6 9714.4 9754.1 5130.4 5131.9 5136.0 5140.7 5140.9 4253.2 4398.0 4563.3 4675.2 4755.4 3632.5 3729.7 3775.9 3789.1 3843.1 1077.7 1084.5 1087.7 1089.2 1089.5 854.6 874.5 890.5 891.1 907.3 5394.2 5465.3 5549.2 5696.1 6000.6 935.4 1010.4 1018.6 1028.0 1036.7 3835.5 3852.7 3874.2 3917.6 3920.6 10767.4 11133.5 11139.4 11142.8 11149.1 11087.7 11090.1 11101.8 11106.8 11202.5 8265.2 8296.1 8345.7 8476.3 8491.4 5717.6 5762.5 5781.6 5833.4 5951.4 10159.3 10209.0 10244.8 10273.7 10457.3 10170.7 10450.8 10725.2 10729.6 10730.2 6824.7 7060.3 7237.0 7470.0 7606.5 8626.6 8760.3 8790.8 8796.6 8801.9 7749.1 7774.1 7938.0 7940.8 8037.3 7624.2 7644.1 7731.3 7759.1 7780.2 6854.1 6938.4 6945.6 6948.7 6956.4 4756.5 4885.0 4905.9 4978.7 5034.2 5010.5 5117.4 5177.7 5217.6 5261.8 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#4] prototype.html Cycles:180 Average:4551.81 Median:5101.23 stddev:1344.06 (26.3%) stddev-sans-first:1309.73 23:36:34 INFO - PROCESS | 13505 | Values: 318.2 324.3 329.3 329.5 332.9 5103.9 5192.9 5256.7 5290.6 5294.0 5154.8 5237.4 5247.4 5282.9 5290.4 4132.3 4139.9 4143.0 4145.3 4222.6 3963.8 4106.4 4181.0 4204.0 4229.3 4085.5 4086.5 4091.2 4096.8 4097.9 4072.7 4076.9 4081.6 4087.0 4119.9 3602.3 3622.8 3641.5 3642.8 3646.1 5087.8 5088.9 5098.5 5119.9 5144.3 4643.7 4681.4 4681.8 4687.7 4688.7 5482.0 5486.9 5490.5 5525.9 5567.0 3787.7 3914.5 3918.3 3918.7 3926.7 5664.3 5671.5 5688.2 5706.2 5723.2 4021.8 4031.6 4049.1 4053.7 4056.3 3986.9 4071.8 4081.7 4092.8 4099.9 4637.3 4644.4 4647.8 4654.9 4659.2 5530.7 5581.4 5588.9 5621.7 5625.8 1329.2 1334.7 1344.3 1396.8 1403.7 872.9 890.7 901.3 901.9 918.5 4752.7 4756.5 4784.3 4792.6 4826.1 4933.8 4959.2 4974.1 4990.7 4999.0 4535.4 4692.6 4706.5 4733.0 4739.7 2576.2 2584.8 2618.9 2659.8 2664.8 5071.9 5115.0 5127.5 5148.9 5151.7 5001.9 5005.4 5131.3 5173.9 5319.4 5815.1 5841.3 5897.9 5908.9 5921.7 5687.1 5698.4 5700.3 5709.6 5754.3 5554.4 5595.7 5646.3 5660.0 5729.5 5853.2 5859.9 5878.6 5882.8 5885.2 4080.4 4140.2 4146.7 4148.3 4160.9 5358.2 5428.7 5538.8 5591.6 5619.0 5345.1 5392.6 5469.5 5471.8 5490.9 5478.2 5478.4 5498.1 5527.4 5537.3 5333.8 5496.3 5548.3 5549.1 5552.3 5537.5 5539.3 5543.8 5559.2 5560.7 5414.0 5433.6 5439.0 5486.5 5492.2 23:36:34 INFO - PROCESS | 13505 | 23:36:34 INFO - PROCESS | 13505 | [#5] yui.html Cycles:180 Average:20550.80 Median:4699.95 stddev:29633.71 (630.5%) stddev-sans-first:29679.49 23:36:34 INFO - PROCESS | 13505 | Values: 737.2 751.7 753.6 793.8 835.7 14476.4 15063.5 15070.1 15079.0 15105.0 14357.6 14394.4 14475.5 14489.7 14515.2 792.7 812.2 814.7 814.8 815.7 647.3 667.1 794.9 814.5 825.5 849.6 854.8 857.4 859.0 859.7 850.0 851.5 855.6 856.7 857.0 503.2 519.6 522.5 523.6 610.7 13999.7 14510.5 14518.3 14519.6 14529.3 14142.7 14630.3 14673.6 14773.3 14914.6 12610.4 12851.7 12881.2 13184.1 13265.7 4328.8 4385.8 4403.3 4421.4 4458.3 76065.0 76631.2 77319.0 77434.0 77485.0 11478.9 11553.7 11587.9 11731.2 11821.4 22502.0 22511.2 22545.0 22546.8 22618.1 8311.4 8565.8 8692.0 8701.6 8895.1 4706.0 4712.6 4746.5 4789.2 4916.1 3501.5 3605.0 3608.3 3613.4 3628.8 604.4 606.3 613.8 623.1 629.8 602.3 616.2 681.4 700.6 711.8 9093.2 9154.4 9402.8 9459.5 9676.2 600.3 658.2 670.5 686.5 699.7 4158.2 4552.9 4693.9 4734.6 4823.6 114557.0 119146.4 120857.0 124425.4 124511.0 61009.0 93542.5 102295.0 102379.5 103950.5 73389.0 74049.0 74528.0 84979.0 86131.0 8925.2 9330.6 9803.9 9808.2 9993.8 49174.5 49808.0 51304.2 51482.5 51642.0 66312.0 72966.0 73939.0 74138.0 77061.0 12620.6 12752.5 12828.2 12941.9 13320.7 13880.7 14139.9 14587.3 14776.9 15360.2 13499.9 13585.1 13707.3 14234.4 14624.9 13442.8 13605.3 13716.9 13847.2 14143.4 12996.3 13130.7 13332.5 13711.9 14219.1 10095.6 10100.5 10101.9 10417.4 10653.5 10282.4 11386.1 11622.7 11864.2 11966.2 23:36:34 INFO - PROCESS | 13505 | -------- Summary: end -------- 23:36:34 INFO - PROCESS | 13505 | 23:36:35 INFO - TEST-INFO | 13505: exit 0 23:36:35 INFO - TEST-OK | dromaeo_css | took 1116762ms 23:36:35 INFO - TEST-START | kraken 23:36:35 INFO - operating with platform_type : linux_ 23:36:35 INFO - Initialising browser for kraken test... 23:36:35 INFO - TEST-INFO | started process 13818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBduK9h/profile http://localhost:33969/getInfo.html) 23:36:39 INFO - PROCESS | 13818 | __metrics Screen width/height:1600/1200 23:36:39 INFO - PROCESS | 13818 | colorDepth:24 23:36:39 INFO - PROCESS | 13818 | Browser inner width/height: 1024/697 23:36:39 INFO - PROCESS | 13818 | __metrics 23:36:40 INFO - PROCESS | 13818 | 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" 23:36:40 INFO - PROCESS | 13818 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:36:40 INFO - PROCESS | 13818 | console.error: PushService: 23:36:40 INFO - PROCESS | 13818 | stateChangeProcessEnqueue: Error transitioning state 23:36:40 INFO - PROCESS | 13818 | UnknownError 23:36:41 INFO - TEST-INFO | 13818: exit 0 23:36:41 INFO - Browser initialized. 23:36:41 INFO - Running cycle 1/1 for kraken test... 23:36:41 INFO - TEST-INFO | started process 13899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBduK9h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:36:53 INFO - PROCESS | 13899 | RSS: Main: 173031424 23:36:53 INFO - PROCESS | 13899 | 23:36:56 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-beat-detection) 23:36:56 INFO - PROCESS | 13899 | RSS: Main: 231280640 23:36:56 INFO - PROCESS | 13899 | 23:36:59 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-dft) 23:37:00 INFO - PROCESS | 13899 | RSS: Main: 216870912 23:37:00 INFO - PROCESS | 13899 | 23:37:03 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-fft) 23:37:04 INFO - PROCESS | 13899 | RSS: Main: 298934272 23:37:04 INFO - PROCESS | 13899 | 23:37:06 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-oscillator) 23:37:06 INFO - PROCESS | 13899 | RSS: Main: 207609856 23:37:06 INFO - PROCESS | 13899 | 23:37:08 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:37:09 INFO - PROCESS | 13899 | RSS: Main: 213405696 23:37:09 INFO - PROCESS | 13899 | 23:37:12 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-darkroom) 23:37:13 INFO - PROCESS | 13899 | RSS: Main: 249176064 23:37:13 INFO - PROCESS | 13899 | 23:37:16 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-desaturate) 23:37:17 INFO - PROCESS | 13899 | RSS: Main: 247398400 23:37:17 INFO - PROCESS | 13899 | 23:37:19 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33969/tests/kraken/driver.html?testName=json-parse-financial) 23:37:20 INFO - PROCESS | 13899 | RSS: Main: 247418880 23:37:20 INFO - PROCESS | 13899 | 23:37:22 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:37:22 INFO - PROCESS | 13899 | RSS: Main: 209063936 23:37:22 INFO - PROCESS | 13899 | 23:37:24 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:37:25 INFO - PROCESS | 13899 | RSS: Main: 217604096 23:37:25 INFO - PROCESS | 13899 | 23:37:27 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:37:27 INFO - PROCESS | 13899 | RSS: Main: 226000896 23:37:27 INFO - PROCESS | 13899 | 23:37:30 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:37:31 INFO - PROCESS | 13899 | RSS: Main: 230498304 23:37:31 INFO - PROCESS | 13899 | 23:37:33 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:37:34 INFO - PROCESS | 13899 | RSS: Main: 231911424 23:37:34 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33969/tests/kraken/driver.html?testName=ai-astar) 23:37:36 INFO - PROCESS | 13899 | RSS: Main: 281980928 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | __start_tp_report 23:37:36 INFO - PROCESS | 13899 | _x_x_mozilla_page_load 23:37:36 INFO - PROCESS | 13899 | _x_x_mozilla_page_load_details 23:37:36 INFO - PROCESS | 13899 | |i|pagename|runs| 23:37:36 INFO - PROCESS | 13899 | |0;ai-astar;110;112;112;114;114;112;112;111;111;112 23:37:36 INFO - PROCESS | 13899 | |1;audio-beat-detection;147;124;123;178;128;129;126;127;162;123 23:37:36 INFO - PROCESS | 13899 | |2;audio-dft;162;162;161;161;163;162;162;161;160;163 23:37:36 INFO - PROCESS | 13899 | |3;audio-fft;84;77;76;110;78;77;75;77;106;76 23:37:36 INFO - PROCESS | 13899 | |4;audio-oscillator;83;82;106;83;93;83;101;82;82;123 23:37:36 INFO - PROCESS | 13899 | |5;imaging-gaussian-blur;117;116;118;117;117;117;117;117;117;117 23:37:36 INFO - PROCESS | 13899 | |6;imaging-darkroom;146;146;145;146;146;146;146;147;146;145 23:37:36 INFO - PROCESS | 13899 | |7;imaging-desaturate;96;97;96;96;96;96;96;96;96;96 23:37:36 INFO - PROCESS | 13899 | |8;json-parse-financial;58;55;53;53;53;53;53;53;54;54 23:37:36 INFO - PROCESS | 13899 | |9;json-stringify-tinderbox;62;63;63;63;64;63;62;63;64;64 23:37:36 INFO - PROCESS | 13899 | |10;stanford-crypto-aes;81;80;80;79;82;86;80;88;79;79 23:37:36 INFO - PROCESS | 13899 | |11;stanford-crypto-ccm;133;134;135;138;133;140;137;130;181;128 23:37:36 INFO - PROCESS | 13899 | |12;stanford-crypto-pbkdf2;166;169;166;164;169;173;164;164;162;165 23:37:36 INFO - PROCESS | 13899 | |13;stanford-crypto-sha256-iterative;66;66;66;65;66;66;68;66;65;65 23:37:36 INFO - PROCESS | 13899 | __end_tp_report 23:37:36 INFO - PROCESS | 13899 | __start_cc_report 23:37:36 INFO - PROCESS | 13899 | _x_x_mozilla_cycle_collect,1655 23:37:36 INFO - PROCESS | 13899 | __end_cc_report 23:37:36 INFO - PROCESS | 13899 | __startTimestamp1476686256318__endTimestamp 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | ------- Summary: start ------- 23:37:36 INFO - PROCESS | 13899 | Number of tests: 14 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:1.25 (1.1%) stddev-sans-first:1.09 23:37:36 INFO - PROCESS | 13899 | Values: 110.0 112.0 112.0 114.0 114.0 112.0 112.0 111.0 111.0 112.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:128.50 stddev:19.24 (15.0%) stddev-sans-first:20.04 23:37:36 INFO - PROCESS | 13899 | Values: 147.0 124.0 123.0 178.0 128.0 129.0 126.0 127.0 162.0 123.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 23:37:36 INFO - PROCESS | 13899 | Values: 162.0 162.0 161.0 161.0 163.0 162.0 162.0 161.0 160.0 163.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.00 stddev:13.13 (17.0%) stddev-sans-first:13.92 23:37:36 INFO - PROCESS | 13899 | Values: 84.0 77.0 76.0 110.0 78.0 77.0 75.0 77.0 106.0 76.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:82.50 stddev:14.07 (17.1%) stddev-sans-first:14.56 23:37:36 INFO - PROCESS | 13899 | Values: 83.0 82.0 106.0 83.0 93.0 83.0 101.0 82.0 82.0 123.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 23:37:36 INFO - PROCESS | 13899 | Values: 117.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 23:37:36 INFO - PROCESS | 13899 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:37:36 INFO - PROCESS | 13899 | Values: 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:1.60 (3.0%) stddev-sans-first:0.73 23:37:36 INFO - PROCESS | 13899 | Values: 58.0 55.0 53.0 53.0 53.0 53.0 53.0 53.0 54.0 54.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 23:37:36 INFO - PROCESS | 13899 | Values: 62.0 63.0 63.0 63.0 64.0 63.0 62.0 63.0 64.0 64.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:80.50 stddev:3.13 (3.9%) stddev-sans-first:3.32 23:37:36 INFO - PROCESS | 13899 | Values: 81.0 80.0 80.0 79.0 82.0 86.0 80.0 88.0 79.0 79.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:136.00 stddev:15.22 (11.2%) stddev-sans-first:15.99 23:37:36 INFO - PROCESS | 13899 | Values: 133.0 134.0 135.0 138.0 133.0 140.0 137.0 130.0 181.0 128.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:166.00 stddev:3.26 (2.0%) stddev-sans-first:3.46 23:37:36 INFO - PROCESS | 13899 | Values: 166.0 169.0 166.0 164.0 169.0 173.0 164.0 164.0 162.0 165.0 23:37:36 INFO - PROCESS | 13899 | 23:37:36 INFO - PROCESS | 13899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 23:37:36 INFO - PROCESS | 13899 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 68.0 66.0 65.0 65.0 23:37:36 INFO - PROCESS | 13899 | -------- Summary: end -------- 23:37:36 INFO - PROCESS | 13899 | 23:37:37 INFO - TEST-INFO | 13899: exit 0 23:37:37 INFO - TEST-OK | kraken | took 62180ms 23:37:37 INFO - SUITE-END | took 1178s 23:37:37 INFO - Completed test suite (00:19:39) 23:37:37 INFO - javascript benchmark 23:37:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2942.161483977067, 2991.685872983338, 2994.5807586937826, 3003.6846131156212, 3038.0733876167774, 5046.646734728562, 5139.897202055957, 5151.81968631098, 5182.937497812949, 5211.426743058265, 5145.022445735308, 5198.0, 5206.375234971809, 5229.3201883755155, 5302.893942121155, 4659.767011649421, 4725.787339569711, 4730.172219861528, 4787.090452813963, 4834.081524510332, 4755.9286610700865, 4803.279508073782, 4828.686135401188, 4845.394325709286, 4873.366462359906, 4817.181079216542, 4822.300766388871, 4840.273958906157, 4851.805927762885, 4905.730184839832, 4637.2116740153915, 4666.53334666534, 4718.8584342469785, 4764.595009424203, 4807.639427042951, 4706.082313948781, 4745.762711864428, 4764.380630518045, 4805.063012712522, 4840.273958906192, 5144.5369916707305, 5188.273641690166, 5198.350206224222, 5203.323567936144, 5225.634205605571, 5389.380221274564, 5412.621116521845, 5431.646942948735, 5548.306461692288, 5554.694491802949, 5499.972500137514, 5530.308711411074, 5542.584306177053, 5602.803901863456, 5605.439456054402, 5146.459621739701, 5154.742262886841, 5156.123459011977, 5157.329547158883, 5224.37307523092, 5502.477264659851, 5550.250716153268, 5594.160875868571, 5614.747336369873, 5626.409227031185, 5350.892982140335, 5410.269613602192, 5412.783488660488, 5413.404525502192, 5416.620836541404, 5480.7533660985355, 5527.751251193706, 5537.833864984057, 5558.166275058693, 5593.524550413179, 5530.695811730313, 5535.308086489189, 5535.391106978229, 5543.944560554423, 5595.132754423071, 5500.614956952975, 5553.722313884289, 5561.944380556223, 5563.944360556424, 5607.943920560824, 4244.448221731217, 4287.614114729689, 4304.634106100953, 4326.956730432718, 4342.6742994275555, 3852.788096654655, 3903.8048097595006, 3904.7657140571655, 3921.11774850662, 3928.332183528807, 5316.149416093406, 5323.414424413311, 5325.818598967524, 5328.706921119376, 5389.218563308298, 5386.973065134727, 5534.529564987021, 5564.4991950724625, 5570.080936645408, 5581.804636837691, 5181.35233095863, 5215.5827533797965, 5231.5030072143845, 5269.314989051399, 5364.91952620711, 4775.904481910342, 4784.210605250095, 4794.065157294294, 4794.520547945178, 4842.467328593852, 5750.30996280449, 5758.942410575841, 5759.366469688247, 5760.0783874579865, 5763.193152958589, 5387.676739395649, 5442.0, 5449.754761035683, 5501.3948465667945, 5507.917381239365, 5726.484616384545, 5730.054541000688, 5731.713414329183, 5737.282839645044, 5785.247917770663, 5384.111621582395, 5424.83725488236, 5429.701366424884, 5430.239766432782, 5432.348118225838, 5392.0, 5487.067198576172, 5493.203485494501, 5529.30883639545, 5600.383957764724, 5357.249985002025, 5362.812301569426, 5383.434739352311, 5411.269478620336, 5427.918581221206, 5390.730463476732, 5400.702961337164, 5407.026735187546, 5409.486098820606, 5471.480209380103, 5243.842684719464, 5305.0, 5322.707251101072, 5386.165144402624, 5399.163129714901, 5451.4276001019325, 5454.290942177491, 5457.372402173801, 5500.092484740133, 5522.088855338824, 5303.761330740049, 5338.46615338463, 5341.305630268041, 5350.30446042012, 5408.972955135199], "value": 5121.89457277701, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.010816203562, 581.5986968991396, 602.0397466041665, 612.0635427795473, 617.4720613875133, 30620.846895765517, 31334.373312533735, 34323.14192145191, 34339.0, 34355.31289374211, 34739.65260347396, 42256.0, 43204.0, 43337.783311083476, 48580.757096214555, 8754.912450875474, 8854.29165666745, 8856.778580535505, 8863.645454181826, 8895.066018068106, 7982.560959147245, 8176.1006289308125, 8731.781705457382, 8831.0, 8832.0, 10497.737556561071, 10732.19508536859, 10736.570537178504, 11091.33451992879, 11107.166962477806, 10699.625513107043, 10766.192535559787, 10775.515101820358, 10776.084032857216, 10778.353298802018, 7731.304182623591, 7940.166282540308, 10165.4917254137, 10548.261621686484, 10619.0, 41003.6946120656, 41387.1722565547, 41423.792881035704, 41462.585374146176, 41466.79266603678, 3470.8438120284395, 3494.7204223662047, 3514.297140571896, 3582.8208589570418, 3734.2158146789207, 29370.11889643314, 30210.69789302101, 31669.20826979321, 31943.361132777445, 32872.0, 4358.302671572533, 4461.219286624828, 4514.41369987379, 4522.638188944877, 4526.144280844838, 330203.0, 332052.0, 332849.0, 333411.0, 334473.0, 57682.13476797851, 58501.414985849595, 58616.70691646515, 58617.0, 58653.413465864796, 61564.69217653883, 61606.69196653988, 61720.0, 61772.837950671514, 61840.69079654663, 15013.174275414738, 15559.14424706652, 15666.295016724274, 15671.843281567266, 15681.529554113395, 12483.251004939551, 12602.684932876751, 12615.621531354074, 12618.432170552347, 12622.242665440102, 16298.755518667229, 16605.91697041507, 17840.28638854431, 18039.91182159786, 18356.724649130272, 6909.447244220551, 6967.372936435745, 7131.286871312931, 7182.784516464514, 7321.670524826395, 947.6020071569957, 1066.7546464313252, 1071.9088877445504, 1075.3010543147018, 1122.2817396866012, 31208.687913121033, 31902.361952760813, 32046.35907281888, 32564.185895352806, 32690.346193076006, 1067.685032915292, 1132.049078773834, 1173.0732721150366, 1188.578054790537, 1200.3578085724096, 16515.752263716055, 17012.48962531126, 17054.48836534881, 17058.57353566146, 17227.818031234165, 525554.0, 530944.0, 531064.0, 531834.0, 532032.0, 46998.29502557465, 48236.0, 52707.209391859156, 54549.0, 54708.0, 1283.8395200599925, 1321.669582604349, 1353.1137105196112, 1353.6345186799508, 1361.251311778518, 29987.846920806394, 32563.348733024734, 32852.0, 33327.83336083304, 33334.66665333413, 49666.255006174215, 50723.492765071875, 50734.49265507298, 50735.23897141472, 50775.0, 344591.0, 345164.0, 345604.0, 346592.0, 346734.0, 28054.2986425341, 28105.859470702515, 28111.43777124487, 28422.715772842836, 28530.286742831595, 45996.0, 46405.0, 47513.52486475091, 48072.0, 48157.5184248153, 39325.60674393334, 39869.0, 40239.396409053305, 40894.38658420066, 42270.154596907276, 39746.0, 39759.71428713772, 39936.80031599823, 40002.79998599988, 40833.0, 41332.58667413287, 41940.16119677528, 42211.36682949697, 42275.36586951137, 42342.0, 33909.66090339164, 35303.0, 35732.0, 35826.641733583376, 36315.45526817047, 27072.0, 27545.724542755117, 27607.171784846487, 27734.861325693244, 27778.305542360795], "value": 19823.653731638366, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.57898574610687, 389.7875657766517, 404.3389058888717, 405.36965019394836, 422.16200841329953, 12934.288614126232, 15016.324265408055, 18457.169427375768, 18982.71525927112, 19334.903325483352, 18720.251189952385, 18932.337368192115, 19263.32578359758, 19549.609007819836, 19572.510687232825, 4962.627802914778, 5554.9722251388785, 5789.594728369016, 5794.855128621776, 5795.536357091443, 5232.581393488512, 5590.804321848736, 5688.60179787415, 5712.942870571283, 5725.7995970141055, 5460.399356070829, 5608.270924779773, 5612.69130197841, 5618.943810561883, 5623.100303951369, 5649.689267090308, 5804.0, 5812.970935145339, 5814.302283725938, 5820.505257053155, 5486.61593688442, 5609.1305847593685, 5639.943600563983, 5645.717714114319, 5661.971690141523, 11757.647270581896, 11892.345920974343, 11901.464434100486, 11909.940450297692, 12632.431540580605, 2208.293346129239, 2331.4521087544413, 2717.904873329424, 2939.2357986923553, 3008.6239220097486, 9598.856017159747, 10228.488575571191, 10232.693019209435, 10239.641612543524, 11154.0, 1762.0837164674426, 1769.7433872088604, 1771.5748220427133, 1810.2306519729063, 1849.9630007399912, 77114.0, 81052.0, 82506.58746706229, 84352.73470898003, 85094.14905850987, 10566.788664226673, 10775.353478791298, 11087.445627718582, 11098.0, 11685.377532964809, 15470.922645386701, 15473.613159671098, 15493.457728979656, 15498.0, 15498.14760188178, 7800.219978002267, 7817.5309481431295, 7976.643970525025, 8016.759497215093, 8150.3479721622125, 7243.963780181065, 7307.26927307265, 7450.664720087609, 7508.662110205054, 7536.284053014955, 4697.637685071291, 4704.95295047052, 4766.356541866873, 4789.880252993703, 4898.167311557044, 1884.8115188481042, 1998.15078591598, 2024.2004408258656, 2025.0887100804696, 2066.597013582337, 827.561392461984, 927.4342650982895, 969.6896992962326, 981.3228872078244, 1078.9028987391173, 18634.0, 19140.0, 19338.90330548338, 19405.223791048484, 19483.790997331078, 966.2173639352147, 973.9951300243595, 977.2279898879948, 980.4313498171043, 993.1210878372548, 9937.493483732904, 10652.786944261072, 10755.892441075646, 10770.730731731614, 10782.137429005661, 393899.0, 398849.0, 410890.0, 411585.0, 412535.0, 83864.0, 85820.57089714636, 86027.0, 87435.0, 88944.5552772232, 998.5620706183073, 1005.1908213887745, 1013.8073765984586, 1015.1016350529834, 1018.3533456255183, 16009.599760006093, 16377.181140943143, 16778.58053548671, 16995.49013529596, 17293.0, 40642.0, 40835.79582102071, 41414.58585414107, 41741.0, 42905.0, 53434.0, 54030.0, 54309.0, 54422.18366724423, 55958.44041559532, 5643.971780141073, 6548.116004339544, 8011.399145064027, 8274.958625207077, 8360.581970901552, 11351.886481135083, 12591.811122833347, 12961.935190323988, 13233.867661323264, 13400.798988014993, 5666.9716651416475, 5730.770769169187, 9655.565499552678, 10235.130013948898, 10578.153747700011, 5417.268668729828, 9215.49314787693, 9650.324477286522, 9906.653267135616, 10774.084202842847, 10772.0, 10974.780504390028, 11069.0, 11689.649310520357, 11817.172797904064, 7980.521168729895, 8073.677052918052, 8195.869074040911, 8430.156984301766, 8787.472751634923, 9536.856947145938, 10696.83954740664, 11057.391843448688, 11776.411179441166, 11797.174197806415], "value": 8684.995878627817, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.3251557963226, 1473.145575566172, 1482.3033174408026, 1650.6616143690544, 1773.911304434778, 7673.0, 8411.242988131075, 8646.178613031767, 8704.521251331174, 9023.684171054014, 8781.692640757576, 8856.468611883276, 8863.512506812123, 8864.246539044188, 8866.645334186642, 2294.9541009179766, 2313.1610369756, 2319.69843920291, 2327.034280773477, 2327.476317828492, 2271.3980795089315, 2288.6910267113894, 2313.8430784607694, 2328.6157783965627, 2343.414146463384, 2279.2022792022826, 2290.0725206291477, 2303.7005189325364, 2329.848559843613, 2339.3917581428864, 2125.2455378340696, 2127.563849410867, 2136.8824714640687, 2145.034734369532, 2198.5273166269167, 2134.8398870084807, 2158.2446143849684, 2166.4800447892394, 2169.0, 2175.401764514771, 8866.556672166365, 8897.065808090121, 8901.154390332907, 8905.910940890573, 8920.330975176823, 5051.722155281458, 5273.367195936474, 5273.6572122811895, 5312.3093997780425, 5317.229001794718, 3288.177955511122, 3390.135515443571, 3483.5819701635714, 3497.9475307870403, 3501.369753444379, 1171.2855158353398, 1217.1419149499582, 1229.0474881966459, 1243.9315837628926, 1245.7321675839737, 12453.75092498154, 12515.624531263986, 12570.805773451508, 12571.93714031433, 12586.370681465982, 4255.3829694694095, 4460.375547423363, 4546.431696037996, 4609.746463944515, 4615.838445654386, 9707.369021013752, 9707.514624268759, 9709.6116155353, 9714.368566043326, 9754.0733630305, 5130.4356520782685, 5131.897362052738, 5136.049830781318, 5140.691558506502, 5140.948590514122, 4253.234417804763, 4398.0324328648285, 4563.292689633112, 4675.158471475167, 4755.358026666425, 3632.54593175853, 3729.664330210294, 3775.9244815104094, 3789.147441825567, 3843.135294558758, 1077.7305673581604, 1084.5499117866154, 1087.6736978906297, 1089.2048804372694, 1089.5042755546208, 854.5513605357237, 874.4753148111082, 890.4612709310903, 891.1311471315545, 907.3467103685336, 5394.217838413408, 5465.344158700902, 5549.223108764776, 5696.060150075274, 6000.609960352563, 935.3966691483955, 1010.3988127064385, 1018.6180182431589, 1027.99486002571, 1036.7356324137297, 3835.53973523179, 3852.749571277858, 3874.205787813548, 3917.5690674025836, 3920.5883382245024, 10767.3539587625, 11133.498992545356, 11139.443027848574, 11142.832857507145, 11149.108071354272, 11087.667369978913, 11090.057345125593, 11101.777964440666, 11106.777864442665, 11202.495887685074, 8265.173482651686, 8296.128906464734, 8345.66617335312, 8476.279516241066, 8491.448055876348, 5717.5997680163655, 5762.4525670061275, 5781.566382521243, 5833.358330583717, 5951.434613711691, 10159.288849780445, 10209.0, 10244.846327305246, 10273.691789246335, 10457.320274182153, 10170.694879153638, 10450.790984180427, 10725.249232553646, 10729.624463143753, 10730.248882578144, 6824.727010919508, 7060.329268719669, 7237.0, 7470.0, 7606.467547271638, 8626.56867156627, 8760.342974276828, 8790.780230494289, 8796.648134074565, 8801.867971980297, 7749.108852482037, 7774.105977812624, 7937.960310198412, 7940.841183176189, 8037.31682806968, 7624.199459056719, 7644.0827100748265, 7731.265529774662, 7759.146493885781, 7780.2219778021745, 6854.143232095988, 6938.444924406136, 6945.583265004116, 6948.687309071003, 6956.40870526011, 4756.476787553297, 4885.0, 4905.877353066202, 4978.726170060681, 5034.244863270539, 5010.549050585465, 5117.4114976776655, 5177.741112944413, 5217.582593392595, 5261.842144735664], "value": 4571.096108298424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.1854452601342, 324.33025801719447, 329.2542391483291, 329.47448819133473, 332.85687154523544, 5103.923441148386, 5192.896142077157, 5256.658317209383, 5290.603204759644, 5294.0, 5154.8453546393575, 5237.423883372836, 5247.396549396821, 5282.867928301785, 5290.444503327153, 4132.338825787867, 4139.867967996484, 4142.958570414303, 4145.2952997990415, 4222.59885310895, 3963.8414463421573, 4106.3635136553885, 4180.958190418088, 4203.978980105096, 4229.344451610005, 4085.46888904442, 4086.5300490443533, 4091.2226676931436, 4096.795160241976, 4097.9180416391655, 4072.7149099563044, 4076.9184616307803, 4081.571434999312, 4087.0, 4119.938200926988, 3602.2975519773654, 3622.836972336251, 3641.5083963665097, 3642.7814331140216, 3646.1431563582537, 5087.821926232564, 5088.949110508885, 5098.5411312982005, 5119.872003199913, 5144.305518754958, 4643.698159619614, 4681.391419115527, 4681.806139434457, 4687.742174180418, 4688.734041808727, 5481.972590137024, 5486.91769623456, 5490.450954904518, 5525.889482210374, 5566.970110529565, 3787.734858559902, 3914.4715463412645, 3918.275119102975, 3918.666913312372, 3926.725129240954, 5664.26364572603, 5671.546276297886, 5688.175214593902, 5706.201131841545, 5723.198752174699, 4021.819018144132, 4031.5565287818317, 4049.1091959768805, 4053.6959728020515, 4056.2698714231133, 3986.920261594752, 4071.75569465833, 4081.7346872453195, 4092.795360232036, 4099.8975025624595, 4637.304404339376, 4644.419447569054, 4647.837325693585, 4654.883627909329, 4659.2312268475325, 5530.723463826793, 5581.358143813432, 5588.91616625751, 5621.747021384048, 5625.846701426198, 1329.1692692067459, 1334.7424455925932, 1344.28080976677, 1396.7834985577251, 1403.7192561487743, 872.9432586881833, 890.7416849113814, 901.2519608724742, 901.9188273055456, 918.522368368458, 4752.714837109785, 4756.548127927841, 4784.277660042382, 4792.616590672807, 4826.0589185108565, 4933.804457016593, 4959.156943319645, 4974.104661160954, 4990.700557966534, 4999.000399840093, 4535.4103966484145, 4692.6245900327895, 4706.505816889246, 4732.95267047332, 4739.66822322441, 2576.175623800366, 2584.8368234294644, 2618.865624516013, 2659.8005149613855, 2664.760886187932, 5071.884185479189, 5115.0, 5127.53852153308, 5148.922766158511, 5151.742412879341, 5001.924586213909, 5005.447221916776, 5131.332316996991, 5173.870653233699, 5319.414864364917, 5815.127730840408, 5841.299044114733, 5897.882042359129, 5908.881822363529, 5921.733521991435, 5687.090065589486, 5698.401667824819, 5700.315962084576, 5709.600327977002, 5754.2519472469985, 5554.361248456317, 5595.748191331481, 5646.322441307069, 5660.0, 5729.54163666914, 5853.2390789198835, 5859.94140058611, 5878.647281163144, 5882.764689412375, 5885.176075349369, 4080.367543030716, 4140.23405669952, 4146.730462519927, 4148.315527937856, 4160.937585936153, 5358.196270559291, 5428.724249801373, 5538.750756215899, 5591.6365436246515, 5619.0, 5345.1447768356875, 5392.595555333287, 5469.535089517435, 5471.781128754806, 5490.91763623538, 5478.205660179331, 5478.370382216638, 5498.086014412368, 5527.447255274601, 5537.307836520435, 5333.839984800462, 5496.31296087989, 5548.250986116984, 5549.084401073777, 5552.333719953632, 5537.501624853782, 5539.25220095282, 5543.77824887, 5559.24950131727, 5560.7219639018695, 5413.9729301353245, 5433.619646624698, 5439.014759612792, 5486.506214440719, 5492.231087647652], "value": 4120.627979955889, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.1854101218154, 751.7406494759313, 753.6495529578741, 793.7539362797534, 835.6740871060288, 14476.420943162275, 15063.472778452757, 15070.095794252353, 15079.0, 15105.0, 14357.641058973531, 14394.352254148562, 14475.493357732481, 14489.710205795878, 14515.201663908481, 792.7225471085103, 812.1999830167281, 814.7392834293029, 814.8125931035866, 815.6981916690833, 647.258888572584, 667.0627767985987, 794.8608993426157, 814.5153633588035, 825.4634487583089, 849.6346570974479, 854.8333075050336, 857.4041041476177, 858.9527575983318, 859.7463748194298, 850.0, 851.4720873058712, 855.6278019061729, 856.6573370651759, 857.0272740439584, 503.21247248056676, 519.5557798082623, 522.5010115339842, 523.5837509180172, 610.7343305662007, 13999.65000874976, 14510.492132775407, 14518.346674399572, 14519.564413067625, 14529.346179421951, 14142.717145657029, 14630.341634626468, 14673.62506623472, 14773.261336933218, 14914.62713432162, 12610.432530536147, 12851.742965140738, 12881.227126372447, 13184.143030702973, 13265.734685306335, 4328.826846926119, 4385.780710964471, 4403.3174857897075, 4421.446396412061, 4458.264386376244, 76065.0, 76631.23368766352, 77319.0, 77434.0, 77485.0, 11478.88521114778, 11553.711157221136, 11587.942060289644, 11731.178817482865, 11821.408929553489, 22502.0, 22511.212107576157, 22545.0, 22546.77453225447, 22618.0952761891, 8311.376646751518, 8565.828683426296, 8691.95654021726, 8701.608427620773, 8895.066018068139, 4706.0, 4712.552307530779, 4746.5253474652945, 4789.233722604366, 4916.0905232532095, 3501.474778783203, 3605.0, 3608.278344331144, 3613.385724426854, 3628.8367023484006, 604.3684349854412, 606.3390903914666, 613.8434699151777, 623.0871772852793, 629.8488362792988, 602.3344204653882, 616.2081724983375, 681.3799442507394, 700.6496751624188, 711.8006958051753, 9093.227075698507, 9154.359194856295, 9402.764930876783, 9459.52702364879, 9676.177524910461, 600.2976517474565, 658.1674182159572, 670.4703284405265, 686.5331574529368, 699.6851416862432, 4158.2307273154565, 4552.855184563859, 4693.906121877543, 4734.597559207506, 4823.589994850407, 114557.0, 119146.40426797984, 120857.0, 124425.37787311187, 124511.0, 61009.0, 93542.53228733949, 102295.0, 102379.48810255901, 103950.48024759827, 73389.0, 74049.0, 74528.0, 84979.0, 86131.0, 8925.241354484942, 9330.582301696653, 9803.852942205729, 9808.166305863797, 9993.750156246151, 49174.50825491842, 49808.0, 51304.23043654273, 51482.48517514927, 51642.0, 66312.0, 72966.0, 73939.0, 74138.0, 77061.0, 12620.55828046014, 12752.48990040388, 12828.230306181285, 12941.935290323489, 13320.666983325495, 13880.652983675489, 14139.929300353433, 14587.343569539184, 14776.852231477547, 15360.23198840076, 13499.86500134986, 13585.116967397087, 13707.314634268447, 14234.359453824396, 14624.85375146235, 13442.798358024833, 13605.319734013457, 13716.862831371558, 13847.238401887993, 14143.363548640542, 12996.285204313854, 13130.73738525205, 13332.466701331838, 13711.862881371058, 14219.146851188962, 10095.646652367132, 10100.494975251355, 10101.949490252502, 10417.374957502574, 10653.520591573553, 10282.370796389596, 11386.146039046938, 11622.6513204604, 11864.169508134348, 11966.162368634112], "value": 7485.759071693061, "unit": "score"}], "name": "dromaeo_css", "value": 7064.937217348148, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 114.0, 114.0, 112.0, 112.0, 111.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 123.0, 178.0, 128.0, 129.0, 126.0, 127.0, 162.0, 123.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 163.0, 162.0, 162.0, 161.0, 160.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 77.0, 76.0, 110.0, 78.0, 77.0, 75.0, 77.0, 106.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 106.0, 83.0, 93.0, 83.0, 101.0, 82.0, 82.0, 123.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 64.0, 63.0, 62.0, 63.0, 64.0, 64.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 79.0, 82.0, 86.0, 80.0, 88.0, 79.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 135.0, 138.0, 133.0, 140.0, 137.0, 130.0, 181.0, 128.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 166.0, 164.0, 169.0, 173.0, 164.0, 164.0, 162.0, 165.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 68.0, 66.0, 65.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1514.2000000000003, "alertThreshold": 2.0}]} 23:37:38 INFO - Return code: 0 23:37:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:37:38 INFO - # TBPL SUCCESS # 23:37:38 INFO - Running post-action listener: _resource_record_post_action 23:37:38 INFO - [mozharness: 2016-10-17 06:37:38.132463Z] Finished run-tests step (success) 23:37:38 INFO - Running post-run listener: _resource_record_post_run 23:37:38 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392798208; Read time: 4; Write time: 242088 23:37:38 INFO - TinderboxPrint: CPU usage
13.1% 23:37:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 23:37:38 INFO - TinderboxPrint: I/O write bytes / time
392,798,208 / 242,088 23:37:38 INFO - TinderboxPrint: CPU idle
8,301.2 (86.9%) 23:37:38 INFO - TinderboxPrint: CPU user
1,199.5 (12.6%) 23:37:38 INFO - TinderboxPrint: Swap in / out
0 / 0 23:37:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103739392; Read time: 0; Write time: 82988 23:37:38 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 289058816; Read time: 0; Write time: 159100 23:37:38 INFO - Running post-run listener: _upload_blobber_files 23:37:38 INFO - Blob upload gear active. 23:37:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:37:38 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 23:37:38 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:37:38 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:37:39 INFO - (blobuploader) - INFO - Open directory for files ... 23:37:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:37:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:37:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:39 INFO - (blobuploader) - INFO - Done attempting. 23:37:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:37:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:37:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:40 INFO - (blobuploader) - INFO - Done attempting. 23:37:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:37:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:44 INFO - (blobuploader) - INFO - Done attempting. 23:37:44 INFO - (blobuploader) - INFO - Iteration through files over. 23:37:44 INFO - Return code: 0 23:37:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:37:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:37:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5dbf196bc783d748ab659a06fd5c93a7ed20a2e83fab6ee6898c81ff4c7de19af73fd11476fb66435173cd6fd29d95aff0c601810ea1a04b228ed975b3001955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f26928a0d9c9f0996ce831fcbc5a2d88ef80dbeab5e6526c3469eecccd7a5ac39f44e1a6646806472e3846b2094a2e2ad339ac70ad2912d688ed85393e46c2c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb47c9ba32a0a45ad44a6f2ed8805dc87d1cc7871663193ab219f6e7423281fea3c757fa22ad08660e85a0d65ba54335d0f48c66750a95809dd6cad8df21caf1"} 23:37:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:37:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:37:44 INFO - Contents: 23:37:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5dbf196bc783d748ab659a06fd5c93a7ed20a2e83fab6ee6898c81ff4c7de19af73fd11476fb66435173cd6fd29d95aff0c601810ea1a04b228ed975b3001955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f26928a0d9c9f0996ce831fcbc5a2d88ef80dbeab5e6526c3469eecccd7a5ac39f44e1a6646806472e3846b2094a2e2ad339ac70ad2912d688ed85393e46c2c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb47c9ba32a0a45ad44a6f2ed8805dc87d1cc7871663193ab219f6e7423281fea3c757fa22ad08660e85a0d65ba54335d0f48c66750a95809dd6cad8df21caf1"} 23:37:44 INFO - Running post-run listener: copy_logs_to_upload_dir 23:37:44 INFO - Copying logs to upload dir... 23:37:44 INFO - mkdir: /builds/slave/test/build/upload/logs 23:37:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.919122 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-10-16 23:37:42.857693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 23:37:42.861581) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5dbf196bc783d748ab659a06fd5c93a7ed20a2e83fab6ee6898c81ff4c7de19af73fd11476fb66435173cd6fd29d95aff0c601810ea1a04b228ed975b3001955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f26928a0d9c9f0996ce831fcbc5a2d88ef80dbeab5e6526c3469eecccd7a5ac39f44e1a6646806472e3846b2094a2e2ad339ac70ad2912d688ed85393e46c2c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb47c9ba32a0a45ad44a6f2ed8805dc87d1cc7871663193ab219f6e7423281fea3c757fa22ad08660e85a0d65ba54335d0f48c66750a95809dd6cad8df21caf1"} build_url:https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011485 build_url: 'https://queue.taskcluster.net/v1/task/VlIQuMY_SXCZLfkKOFRPEg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5dbf196bc783d748ab659a06fd5c93a7ed20a2e83fab6ee6898c81ff4c7de19af73fd11476fb66435173cd6fd29d95aff0c601810ea1a04b228ed975b3001955", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f26928a0d9c9f0996ce831fcbc5a2d88ef80dbeab5e6526c3469eecccd7a5ac39f44e1a6646806472e3846b2094a2e2ad339ac70ad2912d688ed85393e46c2c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb47c9ba32a0a45ad44a6f2ed8805dc87d1cc7871663193ab219f6e7423281fea3c757fa22ad08660e85a0d65ba54335d0f48c66750a95809dd6cad8df21caf1"}' ========= master_lag: 0.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 23:37:43.072538) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:37:43.072883) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1476682528.227045-1687450892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 23:37:43.089187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 23:37:43.089491) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-16 23:37:43.089777) ========= ========= Total master_lag: 0.28 =========