builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1480421643.96 results: success (0) buildid: 20161129013002 builduid: 0e51f9bb06d9493186841087f97b4100 revision: 15b774db7eab7fc4c9489db9c9f77a2e73536e22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:03.964504) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:03.966832) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:03.967281) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:03.983383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:03.983728) ========= 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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-29 04:14:04-- 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% 170K=0.07s 2016-11-29 04:14:04 (170 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.145504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.145831) ========= 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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014565 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.195252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.195616) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 15b774db7eab7fc4c9489db9c9f77a2e73536e22 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 15b774db7eab7fc4c9489db9c9f77a2e73536e22 --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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-29 04:14:04,807 truncating revision to first 12 chars 2016-11-29 04:14:04,807 Setting DEBUG logging. 2016-11-29 04:14:04,807 attempt 1/10 2016-11-29 04:14:04,807 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/15b774db7eab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 04:14:05,100 unpacking tar archive at: mozilla-central-15b774db7eab/testing/mozharness/ program finished with exit code 0 elapsedTime=0.608586 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.817692) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.817993) ========= script_repo_revision: 15b774db7eab7fc4c9489db9c9f77a2e73536e22 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.818406) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.818682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 04:14:04.828496) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-29 04:14:04.828845) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:14:05 INFO - MultiFileLogger online at 20161129 04:14:05 in /builds/slave/test 04:14:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:14:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:14:05 INFO - {'append_to_log': False, 04:14:05 INFO - 'base_work_dir': '/builds/slave/test', 04:14:05 INFO - 'blob_upload_branch': 'Firefox', 04:14:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:14:05 INFO - 'branch': 'Firefox', 04:14:05 INFO - 'buildbot_json_path': 'buildprops.json', 04:14:05 INFO - 'config_files': ('talos/linux_config.py',), 04:14:05 INFO - 'default_actions': ('clobber', 04:14:05 INFO - 'read-buildbot-config', 04:14:05 INFO - 'download-and-extract', 04:14:05 INFO - 'populate-webroot', 04:14:05 INFO - 'create-virtualenv', 04:14:05 INFO - 'install', 04:14:05 INFO - 'run-tests'), 04:14:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:14:05 INFO - 'download_minidump_stackwalk': True, 04:14:05 INFO - 'download_symbols': 'ondemand', 04:14:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:14:05 INFO - 'tooltool.py': '/tools/tooltool.py', 04:14:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:14:05 INFO - '/tools/misc-python/virtualenv.py')}, 04:14:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:14:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:14:05 INFO - 'installer_path': 'installer.exe', 04:14:05 INFO - 'log_level': 'info', 04:14:05 INFO - 'log_name': 'talos', 04:14:05 INFO - 'log_to_console': True, 04:14:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:14:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:14:05 INFO - 'opt_config_files': (), 04:14:05 INFO - 'pip_index': False, 04:14:05 INFO - 'sps_profile': False, 04:14:05 INFO - 'sps_profile_interval': 0, 04:14:05 INFO - 'suite': 'dromaeojs', 04:14:05 INFO - 'system_bits': '32', 04:14:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:14:05 INFO - 'title': 'talos-linux64-ix-032', 04:14:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:14:05 INFO - 'use_talos_json': True, 04:14:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:14:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:14:05 INFO - 'work_dir': 'build'} 04:14:05 INFO - [mozharness: 2016-11-29 12:14:05.540992Z] Running clobber step. 04:14:05 INFO - Running pre-action listener: _resource_record_pre_action 04:14:05 INFO - Running main action method: clobber 04:14:05 INFO - rmtree: /builds/slave/test/build 04:14:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:14:06 INFO - Running post-action listener: _resource_record_post_action 04:14:06 INFO - [mozharness: 2016-11-29 12:14:06.000376Z] Finished clobber step (success) 04:14:06 INFO - [mozharness: 2016-11-29 12:14:06.000477Z] Running read-buildbot-config step. 04:14:06 INFO - Running pre-action listener: _resource_record_pre_action 04:14:06 INFO - Running main action method: read_buildbot_config 04:14:06 INFO - Using buildbot properties: 04:14:06 INFO - { 04:14:06 INFO - "project": "", 04:14:06 INFO - "product": "firefox", 04:14:06 INFO - "script_repo_revision": "production", 04:14:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:14:06 INFO - "repository": "", 04:14:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:14:06 INFO - "buildid": "20161129013002", 04:14:06 INFO - "pgo_build": "True", 04:14:06 INFO - "basedir": "/builds/slave/test", 04:14:06 INFO - "buildnumber": 109, 04:14:06 INFO - "slavename": "talos-linux64-ix-032", 04:14:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:14:06 INFO - "platform": "ubuntu64_hw", 04:14:06 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:14:06 INFO - "branch": "mozilla-central", 04:14:06 INFO - "repo_path": "mozilla-central", 04:14:06 INFO - "revision": "15b774db7eab7fc4c9489db9c9f77a2e73536e22", 04:14:06 INFO - "stage_platform": "linux64-pgo", 04:14:06 INFO - "builduid": "0e51f9bb06d9493186841087f97b4100", 04:14:06 INFO - "slavebuilddir": "test" 04:14:06 INFO - } 04:14:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:14:06 INFO - Running post-action listener: _resource_record_post_action 04:14:06 INFO - [mozharness: 2016-11-29 12:14:06.002952Z] Finished read-buildbot-config step (success) 04:14:06 INFO - [mozharness: 2016-11-29 12:14:06.003041Z] Running download-and-extract step. 04:14:06 INFO - Running pre-action listener: _resource_record_pre_action 04:14:06 INFO - Running main action method: download_and_extract 04:14:06 INFO - mkdir: /builds/slave/test/build/tests 04:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:06 INFO - trying https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:14:06 INFO - Downloaded 1361 bytes. 04:14:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:14:06 INFO - Using the following test package requirements: 04:14:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:14:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:14:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:14:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'jsshell-linux-x86_64.zip'], 04:14:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:14:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:14:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:14:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:14:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:14:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:14:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:14:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:14:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:14:06 INFO - Fetch https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:14:07 INFO - Http code: 200 04:14:07 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 04:14:07 INFO - x-amz-cf-id: IxAKe6oB2nkuUsPYQS_UZHcL4FpTwMpzf6wuZjn-7a1GIVVqDvyorw== 04:14:07 INFO - x-amz-version-id: dv7NnZrDLB0YpqSHech50nhPkXkQ1Ww8 04:14:07 INFO - Expected file size: 21781431 04:14:07 INFO - Obtained file size: 21781431 04:14:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:14:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:14:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:14:11 INFO - Fetch https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:14:12 INFO - Http code: 200 04:14:12 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 04:14:12 INFO - x-amz-cf-id: n9UevDeP79G8wHki-8B-08jn-qcoPn05_0QJ1m5hU6hxWzW4fNi37Q== 04:14:12 INFO - x-amz-version-id: dv7NnZrDLB0YpqSHech50nhPkXkQ1Ww8 04:14:12 INFO - Expected file size: 21781431 04:14:12 INFO - Obtained file size: 21781431 04:14:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:14:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:14:16 INFO - Fetch https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:14:17 INFO - Http code: 200 04:14:17 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 04:14:17 INFO - x-amz-cf-id: FDL-dq1d9SeLbagoafIYTdqhMqCiJvD_XyzA84gM7gTYq6eHQodTMg== 04:14:17 INFO - x-amz-version-id: vTJuBZ6OMKaz6XXver2SXXihjqHurGzE 04:14:17 INFO - Expected file size: 14409702 04:14:17 INFO - Obtained file size: 14409702 04:14:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:17 INFO - trying https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:14:18 INFO - Downloaded 59837016 bytes. 04:14:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:14:18 INFO - mkdir: /builds/slave/test/properties 04:14:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:14:18 INFO - Writing to file /builds/slave/test/properties/build_url 04:14:18 INFO - Contents: 04:14:18 INFO - build_url:https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:14:19 INFO - Running post-action listener: _resource_record_post_action 04:14:19 INFO - Running post-action listener: set_extra_try_arguments 04:14:19 INFO - [mozharness: 2016-11-29 12:14:19.584015Z] Finished download-and-extract step (success) 04:14:19 INFO - [mozharness: 2016-11-29 12:14:19.584314Z] Running populate-webroot step. 04:14:19 INFO - Running pre-action listener: _resource_record_pre_action 04:14:19 INFO - Running main action method: populate_webroot 04:14:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:14:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:14:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:14:19 INFO - '--tppagecycles', 04:14:19 INFO - '7'], 04:14:19 INFO - 'tests': ['tsvgm']}}, 04:14:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:14:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:14:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:14:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:14:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['damp', 'tps']}, 04:14:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'tests': ['damp', 'tps']}, 04:14:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['dromaeo_dom']}, 04:14:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:14:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['basic_compositor_video']}, 04:14:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:14:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'other-e10s': {'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['a11yr', 04:14:19 INFO - 'ts_paint', 04:14:19 INFO - 'tpaint', 04:14:19 INFO - 'sessionrestore', 04:14:19 INFO - 'sessionrestore_no_auto_restore', 04:14:19 INFO - 'tabpaint']}, 04:14:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['tsvgx', 04:14:19 INFO - 'tsvgr_opacity', 04:14:19 INFO - 'tart', 04:14:19 INFO - 'tscrollx', 04:14:19 INFO - 'cart', 04:14:19 INFO - 'tsvg_static']}, 04:14:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:14:19 INFO - 'tsvgr_opacity', 04:14:19 INFO - 'tart', 04:14:19 INFO - 'tscrollx', 04:14:19 INFO - 'cart', 04:14:19 INFO - 'tsvg_static']}, 04:14:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'talos_options': ['--disable-e10s'], 04:14:19 INFO - 'tests': ['tp5o']}, 04:14:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:14:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'talos_options': ['--disable-e10s', 04:14:19 INFO - '--xperf_path', 04:14:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:19 INFO - 'tests': ['tp5n']}, 04:14:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:14:19 INFO - 'talos_options': ['--xperf_path', 04:14:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:19 INFO - 'tests': ['tp5n']}}, 04:14:19 INFO - 'talos.zip': {'path': '', 04:14:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:14:19 INFO - Running post-action listener: _resource_record_post_action 04:14:19 INFO - [mozharness: 2016-11-29 12:14:19.604742Z] Finished populate-webroot step (success) 04:14:19 INFO - [mozharness: 2016-11-29 12:14:19.604833Z] Running create-virtualenv step. 04:14:19 INFO - Running pre-action listener: _resource_record_pre_action 04:14:19 INFO - Running main action method: create_virtualenv 04:14:19 INFO - Creating virtualenv /builds/slave/test/build/venv 04:14:19 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 04:14:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:14:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:14:19 INFO - Using real prefix '/usr' 04:14:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:14:20 INFO - Installing distribute.............................................................................................................................................................................................done. 04:14:22 INFO - Installing pip.................done. 04:14:22 INFO - Return code: 0 04:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:14:22 INFO - Reading from file tmpfile_stdout 04:14:22 INFO - Output received: 04:14:22 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) 04:14:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:14:22 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')]} 04:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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')]} 04:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:22 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 04:14:22 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 04:14:22 INFO - Using env: {'DISPLAY': ':0', 04:14:22 INFO - 'HOME': '/home/cltbld', 04:14:22 INFO - 'LANG': 'en_US.UTF-8', 04:14:22 INFO - 'LANGUAGE': 'en_US:en', 04:14:22 INFO - 'LOGNAME': 'cltbld', 04:14:22 INFO - 'MAIL': '/var/mail/cltbld', 04:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:22 INFO - 'MOZ_NO_REMOTE': '1', 04:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:22 INFO - 'NO_EM_RESTART': '1', 04:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:22 INFO - 'PWD': '/builds/slave/test', 04:14:22 INFO - 'SHELL': '/bin/bash', 04:14:22 INFO - 'SHLVL': '1', 04:14:22 INFO - 'TERM': 'linux', 04:14:22 INFO - 'TMOUT': '86400', 04:14:22 INFO - 'USER': 'cltbld', 04:14:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:22 INFO - '_': '/tools/buildbot/bin/python'} 04:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:22 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 04:14:22 INFO - Cleaning up... 04:14:22 INFO - Return code: 0 04:14:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:14:22 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')]} 04:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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')]} 04:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:22 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 04:14:22 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 04:14:22 INFO - Using env: {'DISPLAY': ':0', 04:14:22 INFO - 'HOME': '/home/cltbld', 04:14:22 INFO - 'LANG': 'en_US.UTF-8', 04:14:22 INFO - 'LANGUAGE': 'en_US:en', 04:14:22 INFO - 'LOGNAME': 'cltbld', 04:14:22 INFO - 'MAIL': '/var/mail/cltbld', 04:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:22 INFO - 'MOZ_NO_REMOTE': '1', 04:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:22 INFO - 'NO_EM_RESTART': '1', 04:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:22 INFO - 'PWD': '/builds/slave/test', 04:14:22 INFO - 'SHELL': '/bin/bash', 04:14:22 INFO - 'SHLVL': '1', 04:14:22 INFO - 'TERM': 'linux', 04:14:22 INFO - 'TMOUT': '86400', 04:14:22 INFO - 'USER': 'cltbld', 04:14:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:22 INFO - '_': '/tools/buildbot/bin/python'} 04:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:22 INFO - Downloading/unpacking psutil>=3.1.1 04:14:22 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 04:14:22 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 04:14:22 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 04:14:22 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 04:14:22 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 04:14:22 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 04:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:14:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:24 INFO - Installing collected packages: psutil 04:14:24 INFO - Running setup.py install for psutil 04:14:24 INFO - building 'psutil._psutil_linux' extension 04:14:24 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 04:14:25 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 04:14:25 INFO - building 'psutil._psutil_posix' extension 04:14:25 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 04:14:25 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 04:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:25 INFO - Successfully installed psutil 04:14:25 INFO - Cleaning up... 04:14:25 INFO - Return code: 0 04:14:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:14:25 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')]} 04:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:25 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')]} 04:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:25 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:25 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 04:14:25 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 04:14:25 INFO - Using env: {'DISPLAY': ':0', 04:14:25 INFO - 'HOME': '/home/cltbld', 04:14:25 INFO - 'LANG': 'en_US.UTF-8', 04:14:25 INFO - 'LANGUAGE': 'en_US:en', 04:14:25 INFO - 'LOGNAME': 'cltbld', 04:14:25 INFO - 'MAIL': '/var/mail/cltbld', 04:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:25 INFO - 'MOZ_NO_REMOTE': '1', 04:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:25 INFO - 'NO_EM_RESTART': '1', 04:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:25 INFO - 'PWD': '/builds/slave/test', 04:14:25 INFO - 'SHELL': '/bin/bash', 04:14:25 INFO - 'SHLVL': '1', 04:14:25 INFO - 'TERM': 'linux', 04:14:25 INFO - 'TMOUT': '86400', 04:14:25 INFO - 'USER': 'cltbld', 04:14:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:25 INFO - '_': '/tools/buildbot/bin/python'} 04:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:14:25 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 04:14:25 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 04:14:25 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 04:14:25 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 04:14:25 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 04:14:25 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 04:14:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:14:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:14:27 INFO - Installing collected packages: mozsystemmonitor 04:14:27 INFO - Running setup.py install for mozsystemmonitor 04:14:27 INFO - Successfully installed mozsystemmonitor 04:14:27 INFO - Cleaning up... 04:14:27 INFO - Return code: 0 04:14:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:14:27 INFO - Using env: {'DISPLAY': ':0', 04:14:27 INFO - 'HOME': '/home/cltbld', 04:14:27 INFO - 'LANG': 'en_US.UTF-8', 04:14:27 INFO - 'LANGUAGE': 'en_US:en', 04:14:27 INFO - 'LOGNAME': 'cltbld', 04:14:27 INFO - 'MAIL': '/var/mail/cltbld', 04:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:27 INFO - 'MOZ_NO_REMOTE': '1', 04:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:27 INFO - 'NO_EM_RESTART': '1', 04:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:27 INFO - 'PWD': '/builds/slave/test', 04:14:27 INFO - 'SHELL': '/bin/bash', 04:14:27 INFO - 'SHLVL': '1', 04:14:27 INFO - 'TERM': 'linux', 04:14:27 INFO - 'TMOUT': '86400', 04:14:27 INFO - 'USER': 'cltbld', 04:14:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:27 INFO - '_': '/tools/buildbot/bin/python'} 04:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:28 INFO - Downloading/unpacking jsonschema==2.5.1 04:14:28 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 04:14:28 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 04:14:28 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 04:14:28 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 04:14:28 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 04:14:28 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 04:14:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:14:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:14:30 INFO - warning: no files found matching '*.txt' 04:14:30 INFO - no previously-included directories found matching 'build' 04:14:30 INFO - no previously-included directories found matching 'dist' 04:14:30 INFO - no previously-included directories found matching '.git*' 04:14:30 INFO - Installing collected packages: jsonschema, functools32 04:14:30 INFO - Running setup.py install for functools32 04:14:30 INFO - warning: no files found matching '*.txt' 04:14:30 INFO - no previously-included directories found matching 'build' 04:14:30 INFO - no previously-included directories found matching 'dist' 04:14:30 INFO - no previously-included directories found matching '.git*' 04:14:30 INFO - Successfully installed jsonschema functools32 04:14:30 INFO - Cleaning up... 04:14:30 INFO - Return code: 0 04:14:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:14:30 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')]} 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 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')]} 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:30 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 04:14:30 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 04:14:30 INFO - Using env: {'DISPLAY': ':0', 04:14:30 INFO - 'HOME': '/home/cltbld', 04:14:30 INFO - 'LANG': 'en_US.UTF-8', 04:14:30 INFO - 'LANGUAGE': 'en_US:en', 04:14:30 INFO - 'LOGNAME': 'cltbld', 04:14:30 INFO - 'MAIL': '/var/mail/cltbld', 04:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:30 INFO - 'MOZ_NO_REMOTE': '1', 04:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:30 INFO - 'NO_EM_RESTART': '1', 04:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:30 INFO - 'PWD': '/builds/slave/test', 04:14:30 INFO - 'SHELL': '/bin/bash', 04:14:30 INFO - 'SHLVL': '1', 04:14:30 INFO - 'TERM': 'linux', 04:14:30 INFO - 'TMOUT': '86400', 04:14:30 INFO - 'USER': 'cltbld', 04:14:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:30 INFO - '_': '/tools/buildbot/bin/python'} 04:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:14:30 INFO - Cleaning up... 04:14:30 INFO - Return code: 0 04:14:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:14:30 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')]} 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 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')]} 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:30 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 04:14:30 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 04:14:30 INFO - Using env: {'DISPLAY': ':0', 04:14:30 INFO - 'HOME': '/home/cltbld', 04:14:30 INFO - 'LANG': 'en_US.UTF-8', 04:14:30 INFO - 'LANGUAGE': 'en_US:en', 04:14:30 INFO - 'LOGNAME': 'cltbld', 04:14:30 INFO - 'MAIL': '/var/mail/cltbld', 04:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:30 INFO - 'MOZ_NO_REMOTE': '1', 04:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:30 INFO - 'NO_EM_RESTART': '1', 04:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:30 INFO - 'PWD': '/builds/slave/test', 04:14:30 INFO - 'SHELL': '/bin/bash', 04:14:30 INFO - 'SHLVL': '1', 04:14:30 INFO - 'TERM': 'linux', 04:14:30 INFO - 'TMOUT': '86400', 04:14:30 INFO - 'USER': 'cltbld', 04:14:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:30 INFO - '_': '/tools/buildbot/bin/python'} 04:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:30 INFO - Downloading/unpacking blobuploader==1.2.4 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:14:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:14:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:14: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 04:14: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 04:14: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 04:14: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 04:14: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 04:14: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 04:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:14:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:14:33 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 04:14:33 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 04:14:33 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 04:14:33 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 04:14:33 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 04:14:33 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 04:14:33 INFO - Downloading docopt-0.6.1.tar.gz 04:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:14:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:14:33 INFO - Running setup.py install for blobuploader 04:14:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:14:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:14:33 INFO - Running setup.py install for requests 04:14:34 INFO - Running setup.py install for docopt 04:14:34 INFO - Successfully installed blobuploader requests docopt 04:14:34 INFO - Cleaning up... 04:14:34 INFO - Return code: 0 04:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:34 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 04:14:34 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 04:14:34 INFO - Using env: {'DISPLAY': ':0', 04:14:34 INFO - 'HOME': '/home/cltbld', 04:14:34 INFO - 'LANG': 'en_US.UTF-8', 04:14:34 INFO - 'LANGUAGE': 'en_US:en', 04:14:34 INFO - 'LOGNAME': 'cltbld', 04:14:34 INFO - 'MAIL': '/var/mail/cltbld', 04:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:34 INFO - 'MOZ_NO_REMOTE': '1', 04:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:34 INFO - 'NO_EM_RESTART': '1', 04:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:34 INFO - 'PWD': '/builds/slave/test', 04:14:34 INFO - 'SHELL': '/bin/bash', 04:14:34 INFO - 'SHLVL': '1', 04:14:34 INFO - 'TERM': 'linux', 04:14:34 INFO - 'TMOUT': '86400', 04:14:34 INFO - 'USER': 'cltbld', 04:14:34 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:34 INFO - '_': '/tools/buildbot/bin/python'} 04:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:34 INFO - Running setup.py (path:/tmp/pip-nE3I4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:34 INFO - Running setup.py (path:/tmp/pip-RHpzrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:34 INFO - Running setup.py (path:/tmp/pip-cKyiDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:34 INFO - Running setup.py (path:/tmp/pip-dKwe0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:34 INFO - Running setup.py (path:/tmp/pip-1mCcZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:34 INFO - Running setup.py (path:/tmp/pip-R73nTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:35 INFO - Running setup.py (path:/tmp/pip-cGMKfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:35 INFO - Running setup.py (path:/tmp/pip-h4h_Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:35 INFO - Running setup.py (path:/tmp/pip-uOh1Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:35 INFO - Running setup.py (path:/tmp/pip-n8E01q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:35 INFO - Running setup.py (path:/tmp/pip-dls96G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:35 INFO - Running setup.py (path:/tmp/pip-78exMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:35 INFO - Running setup.py (path:/tmp/pip-M5rqF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:35 INFO - Running setup.py (path:/tmp/pip-BQGkCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:35 INFO - Running setup.py (path:/tmp/pip-wcLUys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:35 INFO - Running setup.py (path:/tmp/pip-WiMAby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:35 INFO - Running setup.py (path:/tmp/pip-Zgs80G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:14:35 INFO - Running setup.py install for manifestparser 04:14:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:14:35 INFO - Running setup.py install for mozcrash 04:14:36 INFO - Running setup.py install for mozdebug 04:14:36 INFO - Running setup.py install for mozdevice 04:14:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozfile 04:14:36 INFO - Running setup.py install for mozhttpd 04:14:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozinfo 04:14:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozInstall 04:14:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozleak 04:14:36 INFO - Running setup.py install for mozlog 04:14:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for moznetwork 04:14:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozprocess 04:14:37 INFO - Running setup.py install for mozprofile 04:14:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Running setup.py install for mozrunner 04:14:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Running setup.py install for mozscreenshot 04:14:37 INFO - Running setup.py install for moztest 04:14:37 INFO - Running setup.py install for mozversion 04:14:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:14:37 INFO - Cleaning up... 04:14:37 INFO - Return code: 0 04:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:37 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:37 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 04:14:37 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 04:14:37 INFO - Using env: {'DISPLAY': ':0', 04:14:37 INFO - 'HOME': '/home/cltbld', 04:14:37 INFO - 'LANG': 'en_US.UTF-8', 04:14:37 INFO - 'LANGUAGE': 'en_US:en', 04:14:37 INFO - 'LOGNAME': 'cltbld', 04:14:37 INFO - 'MAIL': '/var/mail/cltbld', 04:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:37 INFO - 'MOZ_NO_REMOTE': '1', 04:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:37 INFO - 'NO_EM_RESTART': '1', 04:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:37 INFO - 'PWD': '/builds/slave/test', 04:14:37 INFO - 'SHELL': '/bin/bash', 04:14:37 INFO - 'SHLVL': '1', 04:14:37 INFO - 'TERM': 'linux', 04:14:37 INFO - 'TMOUT': '86400', 04:14:37 INFO - 'USER': 'cltbld', 04:14:37 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:37 INFO - '_': '/tools/buildbot/bin/python'} 04:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:37 INFO - Running setup.py (path:/tmp/pip-2YsPSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:37 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)) 04:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:37 INFO - Running setup.py (path:/tmp/pip-9UhC_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:38 INFO - Running setup.py (path:/tmp/pip-iMjejp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:38 INFO - Running setup.py (path:/tmp/pip-AueCTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:38 INFO - Running setup.py (path:/tmp/pip-ITfu2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:38 INFO - Running setup.py (path:/tmp/pip-2LcJAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:38 INFO - Running setup.py (path:/tmp/pip-M4I_XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:38 INFO - Running setup.py (path:/tmp/pip-lAdrGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:38 INFO - Running setup.py (path:/tmp/pip-2M0OHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:38 INFO - Running setup.py (path:/tmp/pip-SvPbW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:38 INFO - Running setup.py (path:/tmp/pip-53whXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:38 INFO - Running setup.py (path:/tmp/pip-LnJOGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:38 INFO - Running setup.py (path:/tmp/pip-8CidSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:38 INFO - Running setup.py (path:/tmp/pip-U2I3bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:38 INFO - Running setup.py (path:/tmp/pip-yazN57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:38 INFO - Running setup.py (path:/tmp/pip-fpo76j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:39 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)) 04:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:39 INFO - Running setup.py (path:/tmp/pip-5PrSgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:39 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)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:39 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)) 04:14:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:14:39 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 04:14:39 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 04:14:39 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 04:14:39 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 04:14:39 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 04:14:39 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 04:14:41 INFO - Downloading blessings-1.6.tar.gz 04:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:14:41 INFO - Installing collected packages: blessings 04:14:41 INFO - Running setup.py install for blessings 04:14:41 INFO - Successfully installed blessings 04:14:41 INFO - Cleaning up... 04:14:41 INFO - Return code: 0 04:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:14:41 INFO - Reading from file tmpfile_stdout 04:14:41 INFO - Current package versions: 04:14:41 INFO - argparse == 1.2.1 04:14:41 INFO - blessings == 1.6 04:14:41 INFO - blobuploader == 1.2.4 04:14:41 INFO - docopt == 0.6.1 04:14:41 INFO - functools32 == 3.2.3-2 04:14:41 INFO - jsonschema == 2.5.1 04:14:41 INFO - manifestparser == 1.1 04:14:41 INFO - mozInstall == 1.12 04:14:41 INFO - mozcrash == 1.0 04:14:41 INFO - mozdebug == 0.1 04:14:41 INFO - mozdevice == 0.49 04:14:41 INFO - mozfile == 1.2 04:14:41 INFO - mozhttpd == 0.7 04:14:41 INFO - mozinfo == 0.9 04:14:41 INFO - mozleak == 0.1 04:14:41 INFO - mozlog == 3.4 04:14:41 INFO - moznetwork == 0.27 04:14:41 INFO - mozprocess == 0.23 04:14:41 INFO - mozprofile == 0.28 04:14:41 INFO - mozrunner == 6.13 04:14:41 INFO - mozscreenshot == 0.1 04:14:41 INFO - mozsystemmonitor == 0.3 04:14:41 INFO - moztest == 0.7 04:14:41 INFO - mozversion == 1.4 04:14:41 INFO - psutil == 3.1.1 04:14:41 INFO - requests == 1.2.3 04:14:41 INFO - wsgiref == 0.1.2 04:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:41 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 04:14:41 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 04:14:41 INFO - Using env: {'DISPLAY': ':0', 04:14:41 INFO - 'HOME': '/home/cltbld', 04:14:41 INFO - 'LANG': 'en_US.UTF-8', 04:14:41 INFO - 'LANGUAGE': 'en_US:en', 04:14:41 INFO - 'LOGNAME': 'cltbld', 04:14:41 INFO - 'MAIL': '/var/mail/cltbld', 04:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:41 INFO - 'MOZ_NO_REMOTE': '1', 04:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:41 INFO - 'NO_EM_RESTART': '1', 04:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:41 INFO - 'PWD': '/builds/slave/test', 04:14:41 INFO - 'SHELL': '/bin/bash', 04:14:41 INFO - 'SHLVL': '1', 04:14:41 INFO - 'TERM': 'linux', 04:14:41 INFO - 'TMOUT': '86400', 04:14:41 INFO - 'USER': 'cltbld', 04:14:41 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:41 INFO - '_': '/tools/buildbot/bin/python'} 04:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 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)) 04:14:41 INFO - Cleaning up... 04:14:41 INFO - Return code: 0 04:14:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 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 0x7fa5fb5f3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1309130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, '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': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', '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 04:14:41 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 04:14:41 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 04:14:41 INFO - Using env: {'DISPLAY': ':0', 04:14:41 INFO - 'HOME': '/home/cltbld', 04:14:41 INFO - 'LANG': 'en_US.UTF-8', 04:14:41 INFO - 'LANGUAGE': 'en_US:en', 04:14:41 INFO - 'LOGNAME': 'cltbld', 04:14:41 INFO - 'MAIL': '/var/mail/cltbld', 04:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:41 INFO - 'MOZ_NO_REMOTE': '1', 04:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:41 INFO - 'NO_EM_RESTART': '1', 04:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:41 INFO - 'PWD': '/builds/slave/test', 04:14:41 INFO - 'SHELL': '/bin/bash', 04:14:41 INFO - 'SHLVL': '1', 04:14:41 INFO - 'TERM': 'linux', 04:14:41 INFO - 'TMOUT': '86400', 04:14:41 INFO - 'USER': 'cltbld', 04:14:41 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:41 INFO - '_': '/tools/buildbot/bin/python'} 04:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:14:42 INFO - Cleaning up... 04:14:42 INFO - Return code: 0 04:14:42 INFO - Running post-action listener: _resource_record_post_action 04:14:42 INFO - Running post-action listener: _start_resource_monitoring 04:14:42 INFO - Starting resource monitoring. 04:14:42 INFO - [mozharness: 2016-11-29 12:14:42.049295Z] Finished create-virtualenv step (success) 04:14:42 INFO - [mozharness: 2016-11-29 12:14:42.049924Z] Running install step. 04:14:42 INFO - Running pre-action listener: _resource_record_pre_action 04:14:42 INFO - Running main action method: install 04:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:14:42 INFO - Reading from file tmpfile_stdout 04:14:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:14:42 INFO - Reading from file tmpfile_stdout 04:14:42 INFO - Output received: 04:14:42 INFO - Usage: mozinstall [options] installer 04:14:42 INFO - Options: 04:14:42 INFO - -h, --help show this help message and exit 04:14:42 INFO - -d DEST, --destination=DEST 04:14:42 INFO - Directory to install application into. [default: 04:14:42 INFO - "/builds/slave/test"] 04:14:42 INFO - --app=APP Application being installed. [default: firefox] 04:14:42 INFO - mkdir: /builds/slave/test/build/application 04:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:14:59 INFO - Reading from file tmpfile_stdout 04:14:59 INFO - Output received: 04:14:59 INFO - /builds/slave/test/build/application/firefox/firefox 04:14:59 INFO - Running post-action listener: _resource_record_post_action 04:14:59 INFO - [mozharness: 2016-11-29 12:14:59.330596Z] Finished install step (success) 04:14:59 INFO - [mozharness: 2016-11-29 12:14:59.330838Z] Running run-tests step. 04:14:59 INFO - Running pre-action listener: _resource_record_pre_action 04:14:59 INFO - Running main action method: run_tests 04:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:14:59 INFO - Python 2.7.3 04:14:59 INFO - Return code: 0 04:14:59 INFO - grabbing minidump binary from tooltool 04:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:59 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 0x1397fc0>, '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 0x1398ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:14:59 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 04:14:59 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 04:14:59 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 04:14:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:14:59 INFO - Return code: 0 04:14:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:14:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:14:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 04:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 04:14:59 INFO - Using env: {'DISPLAY': ':0', 04:14:59 INFO - 'HOME': '/home/cltbld', 04:14:59 INFO - 'LANG': 'en_US.UTF-8', 04:14:59 INFO - 'LANGUAGE': 'en_US:en', 04:14:59 INFO - 'LOGNAME': 'cltbld', 04:14:59 INFO - 'MAIL': '/var/mail/cltbld', 04:14:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:59 INFO - 'MOZ_NO_REMOTE': '1', 04:14:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:59 INFO - 'NO_EM_RESTART': '1', 04:14:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:59 INFO - 'PWD': '/builds/slave/test', 04:14:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:59 INFO - 'SHELL': '/bin/bash', 04:14:59 INFO - 'SHLVL': '1', 04:14:59 INFO - 'TERM': 'linux', 04:14:59 INFO - 'TMOUT': '86400', 04:14:59 INFO - 'USER': 'cltbld', 04:14:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480421416.176376-1618113868', 04:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:59 INFO - '_': '/tools/buildbot/bin/python'} 04:14:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 04:14:59 INFO - mozversion application_buildid: 20161129013002 04:14:59 INFO - mozversion application_changeset: 15b774db7eab7fc4c9489db9c9f77a2e73536e22 04:14:59 INFO - mozversion application_display_name: Nightly 04:14:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:14:59 INFO - mozversion application_name: Firefox 04:14:59 INFO - mozversion application_remotingname: firefox 04:14:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:14:59 INFO - mozversion application_vendor: Mozilla 04:14:59 INFO - mozversion application_version: 53.0a1 04:14:59 INFO - mozversion platform_buildid: 20161129013002 04:14:59 INFO - mozversion platform_changeset: 15b774db7eab7fc4c9489db9c9f77a2e73536e22 04:14:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:14:59 INFO - mozversion platform_version: 53.0a1 04:14:59 INFO - using testdate: 1480421699 04:14:59 INFO - actual date: 1480421699 04:14:59 INFO - starting webserver on 'localhost:33230' 04:14:59 INFO - SUITE-START | Running 2 tests 04:14:59 INFO - TEST-START | dromaeo_css 04:14:59 INFO - operating with platform_type : linux_ 04:14:59 INFO - Initialising browser for dromaeo_css test... 04:14:59 INFO - TEST-INFO | started process 24952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJwKRXs/profile http://localhost:33230/getInfo.html) 04:15:04 INFO - PROCESS | 24952 | __metrics Screen width/height:1600/1200 04:15:04 INFO - PROCESS | 24952 | colorDepth:24 04:15:04 INFO - PROCESS | 24952 | Browser inner width/height: 1024/697 04:15:04 INFO - PROCESS | 24952 | __metrics 04:15:04 INFO - PROCESS | 24952 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:15:04 INFO - PROCESS | 24952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:05 INFO - PROCESS | 24952 | console.error: PushService: 04:15:05 INFO - PROCESS | 24952 | stateChangeProcessEnqueue: Error transitioning state 04:15:05 INFO - PROCESS | 24952 | UnknownError 04:15:06 INFO - TEST-INFO | 24952: exit 0 04:15:06 INFO - Browser initialized. 04:15:06 INFO - Running cycle 1/1 for dromaeo_css test... 04:15:06 INFO - TEST-INFO | started process 25036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJwKRXs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:18 INFO - PROCESS | 25036 | RSS: Main: 174743552 04:15:18 INFO - PROCESS | 25036 | 04:16:09 INFO - PROCESS | 25036 | 1480421769610 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] 04:18:21 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33230/tests/dromaeo/cssquery-ext.html) 04:18:22 INFO - PROCESS | 25036 | RSS: Main: 312184832 04:18:22 INFO - PROCESS | 25036 | 04:21:25 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-ext.html (next: http://localhost:33230/tests/dromaeo/cssquery-jquery.html) 04:21:25 INFO - PROCESS | 25036 | RSS: Main: 282443776 04:21:25 INFO - PROCESS | 25036 | 04:24:27 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33230/tests/dromaeo/cssquery-mootools.html) 04:24:28 INFO - PROCESS | 25036 | RSS: Main: 222502912 04:24:28 INFO - PROCESS | 25036 | 04:27:30 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33230/tests/dromaeo/cssquery-prototype.html) 04:27:31 INFO - PROCESS | 25036 | RSS: Main: 274300928 04:27:31 INFO - PROCESS | 25036 | 04:30:34 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33230/tests/dromaeo/cssquery-yui.html) 04:30:34 INFO - PROCESS | 25036 | RSS: Main: 218791936 04:30:34 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:33230/tests/dromaeo/cssquery-yui.html (next: http://localhost:33230/tests/dromaeo/cssquery-dojo.html) 04:33:37 INFO - PROCESS | 25036 | RSS: Main: 314761216 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | __start_tp_report 04:33:37 INFO - PROCESS | 25036 | _x_x_mozilla_page_load 04:33:37 INFO - PROCESS | 25036 | _x_x_mozilla_page_load_details 04:33:37 INFO - PROCESS | 25036 | |i|pagename|runs| 04:33:37 INFO - PROCESS | 25036 | |0;dojo.html;3066.8773249070036;3070.723634872861;3073.0473553198526;3093.1803072185876;3118.391913576852;5171.715555644438;5227.9738601307035;5253.106971814791;5341.492558206969;5391.91912121318;5248.291480650111;5268.341457317835;5272.23552584875;5274.208868669702;5277.762500687469;4756.571908528232;4767.404074490689;4785.641076919228;4798.712077275374;4814.542618451242;4831.08209440207;4858.246971719389;4883.706977581339;4888.315635810989;4889.608831293505;4828.517148285161;4862.173430516803;4866.951330486685;4887.828925987574;4888.315635810989;4891.804327826882;4895.755212239392;4897.583705385011;4910.779014944336;4959.479254678243;4690.742009189493;4717.599004084657;4721.811127554928;4818.277258411232;4844.20070688336;5245.239440281176;5301.655392399482;5308.628396012281;5330.1205301125265;5333.280007199017;5676.971615141898;5693.20295158674;5694.601377903548;5713;5715.914261286084;5484.61607687462;5565.026120428909;5739.110437882135;5743.167240750068;5751.683657398841;5153.665011774223;5201.817936372209;5202.557782588485;5213.765380557884;5314.0700377433795;5738.368779434259;5747.425257474303;5759.510441612426;5792.478676919098;5803.448672376117;5496.31296087989;5505.421930697299;5513.255710479114;5515;5522.337319521683;5411.56707463402;5510.752016159283;5520;5624.465675760796;5626.746796394172;5569.303837020372;5595.468430499096;5598.244237027949;5601.915971260434;5634.830955071355;5587.608867379245;5587.888242235133;5603.859903502445;5606.411326810708;5643.604947653625;4205.936910946339;4232.386303985905;4321.092570560218;4331.047169622678;4376.868693939187;3809.866654667129;3846.442265871489;3873.4770805941403;3902.804859757058;3941.684665226775;5432.1580155075235;5447.400785913546;5449.509544141047;5455.781768729206;5455.809046683347;5677.09166533361;5706.201131841545;5723.313202415737;5727.05503591911;5765.942340576625;5256.632035757537;5327.067763141435;5343.973280133652;5396.568274538027;5410.242566040757;4835.20219163834;4848;4855.150348688965;4870.634702397334;4892.877678058077;5876.970615146897;5894.852628684317;5903.144044113579;5939;5939.970300148471;5476.096444086681;5492.670439773627;5576.91634625489;5590.692511911721;5670;5809;5814.3604203538425;5846.327672317568;5863.853403664943;5945.3757355477055;5582.637128586629;5597.692126933058;5628.4090170533145;5633.4084921082695;5649.604527683187;5665.093585026376;5679.659220446786;5680.091185410314;5681.545476361798;5682.232898558642;5378.946210537844;5510.090835012178;5580.609357344947;5582.413846546055;5584.301962254718;5386.13821788512;5476.507114359727;5521.282233309644;5541.806036788693;5621.100623900156;5513.283273174462;5538.141588053858;5539.723013849372;5541.058020136506;5580.665160090408;5600.355959064596;5659.207710920557;5660.179274005329;5665.433456654301;5670.489655930986;5311.229871668509;5348.786048558014;5448.673079615236;5471.316085489314;5474.753636086306 04:33:37 INFO - PROCESS | 25036 | |1;ext.html;544.5163911039755;593.6408472873912;606.5238787551772;633.0156606476927;638.562584629529;32644.510332345006;41477.58522414775;42685.35971960415;42869.35695964563;42876.78561607187;44013.33979990303;44219.77890110553;44299.11401771971;44351.77824110883;44352.556474435325;7857.449978501507;8496.78758031048;8638.09300023498;8638.352123590756;8645.308375329958;7866.056073271216;9991.350562213469;10291.588336466531;10294.897051029506;10634.627788027421;10049.196064314874;10090.394576325407;10091.495425228746;10126.898731012707;10176.236782241325;10268.794624107513;10336.121429678486;10347.74130646736;10352.120069794077;10429.113525350278;10120.797584048352;10274.48627568626;10276.486175691185;10277.486125693686;10286.691399258034;41468.37797433041;41753.79123104396;41805.37291940623;41811.7909410454;41865.37201941973;3669.541307336583;3683.5763887153053;3704.2591481703766;3893.0462036801164;3907.4529565860803;30798.69201308003;30867.382652346827;31073.37853242945;31085.68914310873;32421.51367729486;4103.651189648854;4131.173765246933;4269.765162916038;4360.018995725968;4399.5380485049245;336648;337180;337429;337699;338376;58030.709846450496;58390;58391;58754;58802.11796822966;62649.68675156595;62665.68667156635;62716.37283627105;62792;62825;15813.920930395274;16402.507924762278;16409.6718065638;16433.589160271087;16460.917695411445;11258.38078905668;11600.593979210687;12181.756364872654;12635.115541912162;12666.36668166588;17629.471115866545;17752.822471775376;18154.546136346697;18299.176537055864;18331.908340458212;6043.906675851339;6087.147799308101;6139.938600614026;6159.6612186330185;6170.321264660884;922.3912217936264;1003.7603559603883;1010.1413798271558;1104.1056744037355;1109.20691705431;30464.84767576148;31739.36521269562;31837.88567400176;32027.199320017186;32270.35459290848;1061.4056128568027;1084.1651928015383;1105.2263415608945;1105.4030823355458;1190.922590031645;16991.49025529236;17010.829891700923;17017.149142542825;17035.48893533196;17139.571510712332;458833;459102;461784;462578;464241;55923.440765591826;55981.96162345409;55990.44009559934;56002.43997559972;56026.71986640041;1212.6059030814845;1225.8345123408226;1303.4069498378215;1305.5169587252776;1345.9730805383838;28962;32290.192745181557;32993.835030824695;33294.16764580808;33749;49215.75392123016;49525;49575.50424495853;49602;49808.25287620616;366846;368076;371356.5804274466;371367;371797;27089.1873243803;28298.43403131967;28341;29352.55971160477;29532.261693457836;46286.53713462822;46482;47213.763931180125;47255.29117063178;48349.758251209925;39125.41311880267;39463.60536394599;39706;40149.397759033054;41264.79367603263;39011.2197756049;39313.80343098266;39331.2133757329;39715;41040.58959410487;39440.60559394484;39925.60074399335;40243.3963490542;40539.79730101331;40898;34056.48915266323;35513.64486355103;36793.816030919676;36842.26315473622;36901.44647830338;27514.44971100607;27682.9097598528;27772;27888.58167127535;28012.85993570019 04:33:37 INFO - PROCESS | 25036 | |2;jquery.html;353.12425185539877;368.90961714379984;380.79858047859005;386.86266375436725;403.209708970418;15214.467493637725;15302.464413745507;18592.535186620342;19096.713549296735;19124.23503059878;19068.427947161574;19133.61732765344;19201.903990480063;19355.903220483913;19362.51593710158;4187.74873507589;5395.73021348933;5639.492445679888;5640.887182256352;5642.74607642655;4147.723298594122;6143.385661433843;6286.968565157168;6332.84167895804;6376.617402955814;5957.642541447505;6211.285702144243;6321.24145102586;6330.240371155444;6333.176687030679;6259.593126446789;6274.403931626488;6281.905771413432;6288.402601752826;6300;5431.728413579305;5593;5649.435056494359;5654.038813401731;5671.291088613923;13815.723685526233;13999.720005599831;14048.297585120703;14128.92935535326;14254.92872535641;2262.400463877074;2289.473421113153;2482.36981454187;2870.937753031371;2948.7493563047165;10088.495575221283;10457.111145552637;10486.842697359545;11829.408529573488;14897.627559310995;1869.775626924764;1872.8876267423998;1911.9808801911943;1924.3457224543722;1933.5552822850823;67932.94301756432;70204.6489767553;78625.60687196585;82594;84253;10289.639862604772;10634.946825265979;10990.725231869108;11352.262102963281;11877.406129693481;14790.63023424423;14810.703785924221;14830.40678372853;14847.183404912834;14849.183294918883;7801.6879324827505;7908.2091790821605;8062;8095.595220238964;8184.918150818535;7548.282913123245;7562.697492100255;7574.204708505637;7788.376929845599;7827.1390147083775;4576.153411618795;4580.541945805393;4656.278276867091;4713.3401323814005;4869.707817530889;1923.4133589255132;1945.1830231302886;1949.5905859769323;1957.7944315846917;1965.1746266568075;944.9858252126223;994.4878387630376;1011;1023.5036007536339;1059.655611926127;19366.4190074298;19462.416127516197;21083.894580526998;21164.36506904795;21333.67999480009;979.5885727994117;990.841465365538;1017.730301470111;1027.6968294353185;1041.5000799616228;10314.638987635397;11091.279066860629;11128.721781955515;11172.709594533102;11201.271917325348;325930;326200;421911.2750832366;426638;430346;91092;93003.53498232465;95525;95822.52088739652;96204;1000.7748256642197;1020.4591566469783;1023.3501726403787;1061.315451533772;1062.7502536903821;17268;17665.15423340488;18193.45419637413;18267.269309228013;18437.26250949947;43122.137557249305;43161.35257971196;43339.78330108456;43853.78073109742;44935.77532112318;58092;58573.414265856794;59027;59161.70419147877;59189.40810591839;7933.325667318342;8172.427930045078;8390.28682561989;8427.367947404091;8582.09887961755;10175.949120254352;10861.565537378416;11246.437678115897;11311.208215424842;12099.758004839678;9571.760705982406;10181.84727229107;10182.745431364276;10447.94776026115;10987.890121098686;10574.2598018138;10940.39827809478;10994.395308258123;11031.117510599293;11031.503582338653;10489.423081730578;10513.526891290063;10613;10615.31000484966;11048.281861678965;8154.2253485918745;8202.753917382488;8270.297024752723;8603.397762156588;8662.78343041429;10245.231607629308;10594.205434592282;10738.033576978109;10738.19463540222;10987.230893837355 04:33:37 INFO - PROCESS | 25036 | |3;mootools.html;1295.9352032398378;1432.1621851217035;1480.1341215388998;1571.9685606287874;1736.9044702541355;6973.790786276413;7979.84040319194;8229.794255143624;8235.464694794835;8431.70489032884;8212.178782121784;8280.710175143871;8284.378671599625;8290.212429819157;8292.792680182984;1936.2351871010942;1992.7708313543983;2211.823054155672;2247.359502541776;2249.853759505635;2227.8106360959255;2287.0965968442456;2288.3249441414823;2303.665968434576;2306.550222706573;2214.689943407924;2232.7320721513443;2236.5574204638;2239.552089582082;2240.0143936667896;2292.518571100071;2299.0344055496566;2309.191782875997;2309.642005489135;2311.8266130040147;2149.602323570129;2244;2244.876531790751;2249.876256805875;2252.526969336425;8159.347252219808;8233.17668233178;8238.09380968093;8238.876416853693;8241.587920604006;4830.130576496229;4990.6257030722845;4999.4250661174065;5000.599952003831;5001.574866136383;3104.286014216743;3227.306129182237;3298.802071875695;3309.4043072246986;3325.3183097465203;928;1014.1937159957852;1025.318163421324;1027.4708525109575;1071.5124618298655;10963.50664220112;10987.780244395148;10992.725181870437;10997.12023038155;11008.174386921013;4283.807228674717;4339.522652508222;4391.231534481517;4410.779461026936;4442.666799989949;9599.904000960041;9616.75958101039;9624.75938101553;9629.470379129216;9631.277654175965;4824.445188803333;4846.466888642247;4863.683860549053;4884.902301953941;4889.339939108246;4499.505054444008;4513.7066090704;4522.027764030683;4562;4626.167289887852;3632.54593175853;3670.908227294339;3728.8322025508373;3754.4368344748505;3774.962250377516;1184.662371224197;1194.5938380950345;1197.5988044005182;1218.250775772911;1234.950601975929;886.8403687336213;893.2853717026353;904.0552622509493;920.9999999999866;925.9212966897754;5566.526845218121;5697.9430205698245;5729.138030140138;5730;5804.448577385142;925.6945208081318;940.7883226274171;950.7290422229631;963.9662611808553;966.087047739879;3870.0952608237244;3946.072672921861;3967.662748666338;3997.920041599152;4078.877633670995;10007.249456290807;10156.441395723306;10252.948735256276;10254.128399086012;10268;9567.952160239154;9938.652147174815;9942.602295908082;10124.34191777532;10188.796224075626;7669.501482403626;7844.921550784419;7846.058472983279;7855.528668280036;7856.646450909723;5535.335759708861;5620.494155526022;5691.658500489983;5718.964854379603;5900.380460051632;9117.316201284797;9171.312151588523;9202.585883635118;9214.769630759016;9240.768980775534;8755.781105472413;9060.592273347584;9078.682246121618;9270.212031977357;9286.72139835806;6301.7164227608955;6467.838304042437;6962.1297337832775;7119.8932016020835;7205.243449437943;7817.648205830637;7844.803879903048;7858.528488290721;7889.566073865993;7927.167647397171;7166.964165179141;7199.496035277481;7222.09723784527;7381.077365329334;7450.664720087718;7041.049458323061;7051.471139664443;7134.250903655042;7154.463415243774;7196.388306993964;6424.486041116793;6518.2504012037325;6524.543281970216;6524.706388212637;6525;4640.3735495709;4719.315699223662;4729.810807567659;4753.595944344769;4760.619150468024;4674.322223277538;4727.432708075053;4758.262469317261;4901.828436004866;4913.852584422473 04:33:37 INFO - PROCESS | 25036 | |4;prototype.html;311.9220194951262;326.7239182890458;328.477720424525;330.61153145054556;331.07629713100454;5178.145605975019;5202.739863006854;5286.814961476349;5295.364556253246;5306.48765101946;5221.39953905301;5230.163173892178;5234.345706786652;5237.240600112981;5243;4068.206699693561;4071.1043570414463;4087.690454892976;4089.8364065437345;4119.917601647965;3932.842686292545;3954.1893911748166;4034.616711412411;4049.372347286175;4121.433855135059;4081.653059489947;4104.035551645361;4106.1992911382295;4117.75293482392;4121.319982202933;4094.897627559305;4095.651869591088;4105.281575724266;4109.116539943926;4109.198706252282;3367.276035652322;3372.915677108092;3407.505911642798;3610;3627.9818600907092;5102.438731739506;5114.130597798383;5115.641905066647;5141.820036298712;5151.278820965068;4657.138429390574;4673.158831410345;4675.625949923998;4693.202155633517;4695.295705644146;5347.358317001985;5411.295441935817;5441;5483.314585676791;5523.088690366125;3829.349010668193;3832.2143960488033;3836.5971572984713;3844.961550384516;3854.9229015419814;5598.356189038272;5602.7758889644365;5605.915911261334;5614.382417934024;5683.749575093473;3909.2181563687377;4037.5760545142925;4072.511298644182;4094.488188976378;4099.713020088625;4122.896927576835;4134.855280065183;4137.358709399988;4138.52406973196;4151.7924103794685;4698.577128058423;4703.576678098988;4707.811687532529;4714.976425117852;4723.6065360717985;5423.511883930465;5523.760402305108;5533.944660553423;5569.693666848281;5573.637713548687;1363.8431580368185;1391.5110831410495;1392.1855714407186;1392.4430227908715;1394.2122700674088;915.6383228624655;938.7981583959481;953.6137864165024;968.2399316536485;970.6069042037985;5102.33669622954;5106.66806657566;5129.640365431943;5133.897322053613;5136.994858008107;5328.401479556195;5392.973035134799;5413.756380962866;5419.430959749169;5511.807086751945;5336.839894803162;5365.463453654604;5368.436314186954;5368.651037682538;5399.811006614828;2887.826730396141;2897.942460852776;2899.8985035523656;2917.5040243158714;2922.517784565523;5512.719931640748;5813.168851811751;5898.84972430372;5921;5928.110783382441;5757.165211044375;5801.651900885876;5824.504917082096;5841.269841269841;5846.736896839652;5628.887422251614;5823.2429784128635;5830.7959221427045;5936.821895343147;5950.19672344228;5691.4877661010705;5694.4590263924865;5712.11462223356;5719.113537401709;5742.626729262584;5561.805336813192;5594.496495315441;5605.887882242251;5668.2995101470215;5677.971610141923;5643.943560564342;5829.562782791223;5855.741015681649;5871.970640146772;5874.206982057368;4043.2924238258774;4048.40088165179;4056.7565946043333;4056.9188616228103;4076.2255171518505;5373.313343328336;5700.25896633435;5700.344460386943;5708.828735137952;5711.771529138788;5542.5565954722715;5567.359753628384;5576.609637325349;5576.888462230651;5592.197305831911;5537.972310138423;5543.364707411404;5552.583556233218;5560.805371812129;5569.44305569456;5225.32789507354;5422.403535611158;5495.2083062686615;5508.889822203613;5518.751656175402;5541.667499950016;5570.721463926707;5573.944260557342;5578.525825304894;5580.274564306615;5234.371875374979;5297.33134062769;5371.355437347544;5471.972640136773;5473.507384335429 04:33:37 INFO - PROCESS | 25036 | |5;yui.html;708.355396589104;731.0751898847955;757.8787394016958;779.1948983658829;819.0949001353506;15609.921950390246;16554.66890662186;16564.420245291403;16740.49778506645;16891;15065.6986860263;15075.472358467456;15392.307346169422;15404.922975385136;15407.07557546548;773.9729109481169;793.5675057093869;796.2037962037962;796.410656114474;798.1539568057848;675.0953722012503;675.2403546010738;676.9458443324547;683.0880774166494;779.3726050529322;817.4849844597895;829.1915382502073;831.9126491718373;836.6946064686376;836.9874451883227;823.0945959444623;823.7281697039992;823.7652269103337;824.2540500846713;824.6206744897354;517.1183132758675;599.379642070455;599.6641880546907;599.733118762151;602.713710987282;12889.09776315649;13945;14717.705645887023;14738.852611473856;15220.086794792347;14594.927025364805;14693.485727999469;14968.251587420586;15005.399784008627;15031.173285469295;11715.648530544097;11855.46650400734;11886.465109070112;12135.332556709378;12334.568290109803;3721.2371463849845;3829.3872980323295;3883.514560679915;3981.5023122109737;3984;78923;80428.59785701035;81114.59442702807;82345;82817;10813.36853115499;10910.072643825206;10953.780924381468;11203.271787333797;11260.887391126147;21397.14411423557;21436.892815535823;21456.785432145793;21517.462063448227;21608;7901.565413902175;8028.839423211503;8076.152004039492;8107.4324797263625;8151.30713889326;4253.978730106392;4255.234057869613;4320.308750599952;4347.5652434756275;4423.336499525097;3518.0677120563073;3632.164602141522;3654.488371627974;3660.743747937619;3662.6520480554304;591.8461200088008;603.8943184942706;613.7330261336333;614.4163045107166;618.5793660310942;629.6662768732579;664.2726214794792;695.8636984457768;726.5459088069956;742.2836962331376;9558;9815.407432476108;10025.19798416125;10184.439855808001;10322.535485903152;669.5948950884645;683.2689022745615;698.2563569798149;702.3082263969985;716.9569825810468;4413.602775750198;4789.544993225638;4839.128956787812;4974.920769595503;5017.397912250481;83345.22805423984;83706.58146709349;85073.57463212645;86637;88281;73214.63392683108;73391;73435;74737;75253.62373188099;68615;68845.31154688488;68977.96533052008;69272.6536367325;71169;10032.147267482345;10262.77756532195;10308.32995855267;10478.30768003833;10500.422476763852;45119.32321015122;45376.54623453723;45895.770521147184;46008;47482;58196.4180358191;62893.503014065784;64406.67796660987;66002.66998664977;66055.669721653;13013.674658133623;13370.732585348433;13390.062695611603;13663.316834158053;13689.383977720827;14401;15266.31301591409;15349.23253837326;15433.922830386226;15888.2055897207;13728.862711373158;13844.377003034686;13917.304134793423;14967.850321496646;15130.319135638703;13526.526571569948;13996.860031399963;14306;14379.111102225294;14596.489122880648;13859.149347271741;13882.09766365183;14121.2233327168;14485.85514144845;14815.703685926006;11476.942615286871;11673.357965311989;11773.445857601359;12068.155229133876;12143.21069130539;11124.332540047622;12249.56841440196;12487.375631218221;12826.23042617446;12911.418986145834 04:33:37 INFO - PROCESS | 25036 | __end_tp_report 04:33:37 INFO - PROCESS | 25036 | __start_cc_report 04:33:37 INFO - PROCESS | 25036 | _x_x_mozilla_cycle_collect,1284 04:33:37 INFO - PROCESS | 25036 | __end_cc_report 04:33:37 INFO - PROCESS | 25036 | __startTimestamp1480422817880__endTimestamp 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | ------- Summary: start ------- 04:33:37 INFO - PROCESS | 25036 | Number of tests: 6 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#0] dojo.html Cycles:165 Average:5258.47 Median:5474.75 stddev:592.87 (10.8%) stddev-sans-first:569.22 04:33:37 INFO - PROCESS | 25036 | Values: 3066.9 3070.7 3073.0 3093.2 3118.4 5171.7 5228.0 5253.1 5341.5 5391.9 5248.3 5268.3 5272.2 5274.2 5277.8 4756.6 4767.4 4785.6 4798.7 4814.5 4831.1 4858.2 4883.7 4888.3 4889.6 4828.5 4862.2 4867.0 4887.8 4888.3 4891.8 4895.8 4897.6 4910.8 4959.5 4690.7 4717.6 4721.8 4818.3 4844.2 5245.2 5301.7 5308.6 5330.1 5333.3 5677.0 5693.2 5694.6 5713.0 5715.9 5484.6 5565.0 5739.1 5743.2 5751.7 5153.7 5201.8 5202.6 5213.8 5314.1 5738.4 5747.4 5759.5 5792.5 5803.4 5496.3 5505.4 5513.3 5515.0 5522.3 5411.6 5510.8 5520.0 5624.5 5626.7 5569.3 5595.5 5598.2 5601.9 5634.8 5587.6 5587.9 5603.9 5606.4 5643.6 4205.9 4232.4 4321.1 4331.0 4376.9 3809.9 3846.4 3873.5 3902.8 3941.7 5432.2 5447.4 5449.5 5455.8 5455.8 5677.1 5706.2 5723.3 5727.1 5765.9 5256.6 5327.1 5344.0 5396.6 5410.2 4835.2 4848.0 4855.2 4870.6 4892.9 5877.0 5894.9 5903.1 5939.0 5940.0 5476.1 5492.7 5576.9 5590.7 5670.0 5809.0 5814.4 5846.3 5863.9 5945.4 5582.6 5597.7 5628.4 5633.4 5649.6 5665.1 5679.7 5680.1 5681.5 5682.2 5378.9 5510.1 5580.6 5582.4 5584.3 5386.1 5476.5 5521.3 5541.8 5621.1 5513.3 5538.1 5539.7 5541.1 5580.7 5600.4 5659.2 5660.2 5665.4 5670.5 5311.2 5348.8 5448.7 5471.3 5474.8 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#1] ext.html Cycles:180 Average:55747.84 Median:36871.85 stddev:103626.28 (281.0%) stddev-sans-first:103834.09 04:33:37 INFO - PROCESS | 25036 | Values: 544.5 593.6 606.5 633.0 638.6 32644.5 41477.6 42685.4 42869.4 42876.8 44013.3 44219.8 44299.1 44351.8 44352.6 7857.4 8496.8 8638.1 8638.4 8645.3 7866.1 9991.4 10291.6 10294.9 10634.6 10049.2 10090.4 10091.5 10126.9 10176.2 10268.8 10336.1 10347.7 10352.1 10429.1 10120.8 10274.5 10276.5 10277.5 10286.7 41468.4 41753.8 41805.4 41811.8 41865.4 3669.5 3683.6 3704.3 3893.0 3907.5 30798.7 30867.4 31073.4 31085.7 32421.5 4103.7 4131.2 4269.8 4360.0 4399.5 336648.0 337180.0 337429.0 337699.0 338376.0 58030.7 58390.0 58391.0 58754.0 58802.1 62649.7 62665.7 62716.4 62792.0 62825.0 15813.9 16402.5 16409.7 16433.6 16460.9 11258.4 11600.6 12181.8 12635.1 12666.4 17629.5 17752.8 18154.5 18299.2 18331.9 6043.9 6087.1 6139.9 6159.7 6170.3 922.4 1003.8 1010.1 1104.1 1109.2 30464.8 31739.4 31837.9 32027.2 32270.4 1061.4 1084.2 1105.2 1105.4 1190.9 16991.5 17010.8 17017.1 17035.5 17139.6 458833.0 459102.0 461784.0 462578.0 464241.0 55923.4 55982.0 55990.4 56002.4 56026.7 1212.6 1225.8 1303.4 1305.5 1346.0 28962.0 32290.2 32993.8 33294.2 33749.0 49215.8 49525.0 49575.5 49602.0 49808.3 366846.0 368076.0 371356.6 371367.0 371797.0 27089.2 28298.4 28341.0 29352.6 29532.3 46286.5 46482.0 47213.8 47255.3 48349.8 39125.4 39463.6 39706.0 40149.4 41264.8 39011.2 39313.8 39331.2 39715.0 41040.6 39440.6 39925.6 40243.4 40539.8 40898.0 34056.5 35513.6 36793.8 36842.3 36901.4 27514.4 27682.9 27772.0 27888.6 28012.9 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#2] jquery.html Cycles:180 Average:25753.35 Median:2275.94 stddev:64980.33 (2855.1%) stddev-sans-first:65134.63 04:33:37 INFO - PROCESS | 25036 | Values: 353.1 368.9 380.8 386.9 403.2 15214.5 15302.5 18592.5 19096.7 19124.2 19068.4 19133.6 19201.9 19355.9 19362.5 4187.7 5395.7 5639.5 5640.9 5642.7 4147.7 6143.4 6287.0 6332.8 6376.6 5957.6 6211.3 6321.2 6330.2 6333.2 6259.6 6274.4 6281.9 6288.4 6300.0 5431.7 5593.0 5649.4 5654.0 5671.3 13815.7 13999.7 14048.3 14128.9 14254.9 2262.4 2289.5 2482.4 2870.9 2948.7 10088.5 10457.1 10486.8 11829.4 14897.6 1869.8 1872.9 1912.0 1924.3 1933.6 67932.9 70204.6 78625.6 82594.0 84253.0 10289.6 10634.9 10990.7 11352.3 11877.4 14790.6 14810.7 14830.4 14847.2 14849.2 7801.7 7908.2 8062.0 8095.6 8184.9 7548.3 7562.7 7574.2 7788.4 7827.1 4576.2 4580.5 4656.3 4713.3 4869.7 1923.4 1945.2 1949.6 1957.8 1965.2 945.0 994.5 1011.0 1023.5 1059.7 19366.4 19462.4 21083.9 21164.4 21333.7 979.6 990.8 1017.7 1027.7 1041.5 10314.6 11091.3 11128.7 11172.7 11201.3 325930.0 326200.0 421911.3 426638.0 430346.0 91092.0 93003.5 95525.0 95822.5 96204.0 1000.8 1020.5 1023.4 1061.3 1062.8 17268.0 17665.2 18193.5 18267.3 18437.3 43122.1 43161.4 43339.8 43853.8 44935.8 58092.0 58573.4 59027.0 59161.7 59189.4 7933.3 8172.4 8390.3 8427.4 8582.1 10175.9 10861.6 11246.4 11311.2 12099.8 9571.8 10181.8 10182.7 10447.9 10987.9 10574.3 10940.4 10994.4 11031.1 11031.5 10489.4 10513.5 10613.0 10615.3 11048.3 8154.2 8202.8 8270.3 8603.4 8662.8 10245.2 10594.2 10738.0 10738.2 10987.2 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#3] mootools.html Cycles:180 Average:5395.61 Median:4907.84 stddev:2992.69 (61.0%) stddev-sans-first:2985.22 04:33:37 INFO - PROCESS | 25036 | Values: 1295.9 1432.2 1480.1 1572.0 1736.9 6973.8 7979.8 8229.8 8235.5 8431.7 8212.2 8280.7 8284.4 8290.2 8292.8 1936.2 1992.8 2211.8 2247.4 2249.9 2227.8 2287.1 2288.3 2303.7 2306.6 2214.7 2232.7 2236.6 2239.6 2240.0 2292.5 2299.0 2309.2 2309.6 2311.8 2149.6 2244.0 2244.9 2249.9 2252.5 8159.3 8233.2 8238.1 8238.9 8241.6 4830.1 4990.6 4999.4 5000.6 5001.6 3104.3 3227.3 3298.8 3309.4 3325.3 928.0 1014.2 1025.3 1027.5 1071.5 10963.5 10987.8 10992.7 10997.1 11008.2 4283.8 4339.5 4391.2 4410.8 4442.7 9599.9 9616.8 9624.8 9629.5 9631.3 4824.4 4846.5 4863.7 4884.9 4889.3 4499.5 4513.7 4522.0 4562.0 4626.2 3632.5 3670.9 3728.8 3754.4 3775.0 1184.7 1194.6 1197.6 1218.3 1235.0 886.8 893.3 904.1 921.0 925.9 5566.5 5697.9 5729.1 5730.0 5804.4 925.7 940.8 950.7 964.0 966.1 3870.1 3946.1 3967.7 3997.9 4078.9 10007.2 10156.4 10252.9 10254.1 10268.0 9568.0 9938.7 9942.6 10124.3 10188.8 7669.5 7844.9 7846.1 7855.5 7856.6 5535.3 5620.5 5691.7 5719.0 5900.4 9117.3 9171.3 9202.6 9214.8 9240.8 8755.8 9060.6 9078.7 9270.2 9286.7 6301.7 6467.8 6962.1 7119.9 7205.2 7817.6 7844.8 7858.5 7889.6 7927.2 7167.0 7199.5 7222.1 7381.1 7450.7 7041.0 7051.5 7134.3 7154.5 7196.4 6424.5 6518.3 6524.5 6524.7 6525.0 4640.4 4719.3 4729.8 4753.6 4760.6 4674.3 4727.4 4758.3 4901.8 4913.9 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#4] prototype.html Cycles:180 Average:4629.81 Median:5296.35 stddev:1370.88 (25.9%) stddev-sans-first:1335.86 04:33:37 INFO - PROCESS | 25036 | Values: 311.9 326.7 328.5 330.6 331.1 5178.1 5202.7 5286.8 5295.4 5306.5 5221.4 5230.2 5234.3 5237.2 5243.0 4068.2 4071.1 4087.7 4089.8 4119.9 3932.8 3954.2 4034.6 4049.4 4121.4 4081.7 4104.0 4106.2 4117.8 4121.3 4094.9 4095.7 4105.3 4109.1 4109.2 3367.3 3372.9 3407.5 3610.0 3628.0 5102.4 5114.1 5115.6 5141.8 5151.3 4657.1 4673.2 4675.6 4693.2 4695.3 5347.4 5411.3 5441.0 5483.3 5523.1 3829.3 3832.2 3836.6 3845.0 3854.9 5598.4 5602.8 5605.9 5614.4 5683.7 3909.2 4037.6 4072.5 4094.5 4099.7 4122.9 4134.9 4137.4 4138.5 4151.8 4698.6 4703.6 4707.8 4715.0 4723.6 5423.5 5523.8 5533.9 5569.7 5573.6 1363.8 1391.5 1392.2 1392.4 1394.2 915.6 938.8 953.6 968.2 970.6 5102.3 5106.7 5129.6 5133.9 5137.0 5328.4 5393.0 5413.8 5419.4 5511.8 5336.8 5365.5 5368.4 5368.7 5399.8 2887.8 2897.9 2899.9 2917.5 2922.5 5512.7 5813.2 5898.8 5921.0 5928.1 5757.2 5801.7 5824.5 5841.3 5846.7 5628.9 5823.2 5830.8 5936.8 5950.2 5691.5 5694.5 5712.1 5719.1 5742.6 5561.8 5594.5 5605.9 5668.3 5678.0 5643.9 5829.6 5855.7 5872.0 5874.2 4043.3 4048.4 4056.8 4056.9 4076.2 5373.3 5700.3 5700.3 5708.8 5711.8 5542.6 5567.4 5576.6 5576.9 5592.2 5538.0 5543.4 5552.6 5560.8 5569.4 5225.3 5422.4 5495.2 5508.9 5518.8 5541.7 5570.7 5573.9 5578.5 5580.3 5234.4 5297.3 5371.4 5472.0 5473.5 04:33:37 INFO - PROCESS | 25036 | 04:33:37 INFO - PROCESS | 25036 | [#5] yui.html Cycles:180 Average:18661.63 Median:4333.94 stddev:24283.06 (560.3%) stddev-sans-first:24313.76 04:33:37 INFO - PROCESS | 25036 | Values: 708.4 731.1 757.9 779.2 819.1 15609.9 16554.7 16564.4 16740.5 16891.0 15065.7 15075.5 15392.3 15404.9 15407.1 774.0 793.6 796.2 796.4 798.2 675.1 675.2 676.9 683.1 779.4 817.5 829.2 831.9 836.7 837.0 823.1 823.7 823.8 824.3 824.6 517.1 599.4 599.7 599.7 602.7 12889.1 13945.0 14717.7 14738.9 15220.1 14594.9 14693.5 14968.3 15005.4 15031.2 11715.6 11855.5 11886.5 12135.3 12334.6 3721.2 3829.4 3883.5 3981.5 3984.0 78923.0 80428.6 81114.6 82345.0 82817.0 10813.4 10910.1 10953.8 11203.3 11260.9 21397.1 21436.9 21456.8 21517.5 21608.0 7901.6 8028.8 8076.2 8107.4 8151.3 4254.0 4255.2 4320.3 4347.6 4423.3 3518.1 3632.2 3654.5 3660.7 3662.7 591.8 603.9 613.7 614.4 618.6 629.7 664.3 695.9 726.5 742.3 9558.0 9815.4 10025.2 10184.4 10322.5 669.6 683.3 698.3 702.3 717.0 4413.6 4789.5 4839.1 4974.9 5017.4 83345.2 83706.6 85073.6 86637.0 88281.0 73214.6 73391.0 73435.0 74737.0 75253.6 68615.0 68845.3 68978.0 69272.7 71169.0 10032.1 10262.8 10308.3 10478.3 10500.4 45119.3 45376.5 45895.8 46008.0 47482.0 58196.4 62893.5 64406.7 66002.7 66055.7 13013.7 13370.7 13390.1 13663.3 13689.4 14401.0 15266.3 15349.2 15433.9 15888.2 13728.9 13844.4 13917.3 14967.9 15130.3 13526.5 13996.9 14306.0 14379.1 14596.5 13859.1 13882.1 14121.2 14485.9 14815.7 11476.9 11673.4 11773.4 12068.2 12143.2 11124.3 12249.6 12487.4 12826.2 12911.4 04:33:37 INFO - PROCESS | 25036 | -------- Summary: end -------- 04:33:37 INFO - PROCESS | 25036 | 04:33:38 INFO - TEST-INFO | 25036: exit 0 04:33:38 INFO - TEST-OK | dromaeo_css | took 1119247ms 04:33:38 INFO - TEST-START | kraken 04:33:38 INFO - operating with platform_type : linux_ 04:33:38 INFO - Initialising browser for kraken test... 04:33:38 INFO - TEST-INFO | started process 25340 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPuXVXE/profile http://localhost:33230/getInfo.html) 04:33:43 INFO - PROCESS | 25340 | __metrics Screen width/height:1600/1200 04:33:43 INFO - PROCESS | 25340 | colorDepth:24 04:33:43 INFO - PROCESS | 25340 | Browser inner width/height: 1024/697 04:33:43 INFO - PROCESS | 25340 | __metrics 04:33:44 INFO - PROCESS | 25340 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:33:44 INFO - PROCESS | 25340 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:33:44 INFO - PROCESS | 25340 | console.error: PushService: 04:33:44 INFO - PROCESS | 25340 | stateChangeProcessEnqueue: Error transitioning state 04:33:44 INFO - PROCESS | 25340 | UnknownError 04:33:45 INFO - TEST-INFO | 25340: exit 0 04:33:45 INFO - Browser initialized. 04:33:45 INFO - Running cycle 1/1 for kraken test... 04:33:45 INFO - TEST-INFO | started process 25425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPuXVXE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:57 INFO - PROCESS | 25425 | RSS: Main: 173875200 04:33:57 INFO - PROCESS | 25425 | 04:34:00 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33230/tests/kraken/driver.html?testName=audio-beat-detection) 04:34:01 INFO - PROCESS | 25425 | RSS: Main: 235388928 04:34:01 INFO - PROCESS | 25425 | 04:34:03 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33230/tests/kraken/driver.html?testName=audio-dft) 04:34:04 INFO - PROCESS | 25425 | RSS: Main: 216764416 04:34:04 INFO - PROCESS | 25425 | 04:34:07 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33230/tests/kraken/driver.html?testName=audio-fft) 04:34:07 INFO - PROCESS | 25425 | RSS: Main: 299081728 04:34:07 INFO - PROCESS | 25425 | 04:34:09 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33230/tests/kraken/driver.html?testName=audio-oscillator) 04:34:10 INFO - PROCESS | 25425 | RSS: Main: 209014784 04:34:10 INFO - PROCESS | 25425 | 04:34:12 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:34:13 INFO - PROCESS | 25425 | RSS: Main: 213905408 04:34:13 INFO - PROCESS | 25425 | 04:34:16 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33230/tests/kraken/driver.html?testName=imaging-darkroom) 04:34:16 INFO - PROCESS | 25425 | RSS: Main: 253759488 04:34:16 INFO - PROCESS | 25425 | 04:34:20 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33230/tests/kraken/driver.html?testName=imaging-desaturate) 04:34:20 INFO - PROCESS | 25425 | RSS: Main: 252870656 04:34:20 INFO - PROCESS | 25425 | 04:34:23 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33230/tests/kraken/driver.html?testName=json-parse-financial) 04:34:24 INFO - PROCESS | 25425 | RSS: Main: 252989440 04:34:24 INFO - PROCESS | 25425 | 04:34:25 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:34:26 INFO - PROCESS | 25425 | RSS: Main: 214126592 04:34:26 INFO - PROCESS | 25425 | 04:34:28 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:34:28 INFO - PROCESS | 25425 | RSS: Main: 221257728 04:34:28 INFO - PROCESS | 25425 | 04:34:30 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:34:31 INFO - PROCESS | 25425 | RSS: Main: 230723584 04:34:31 INFO - PROCESS | 25425 | 04:34:34 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:34:34 INFO - PROCESS | 25425 | RSS: Main: 238731264 04:34:34 INFO - PROCESS | 25425 | 04:34:37 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:34:38 INFO - PROCESS | 25425 | RSS: Main: 236314624 04:34:38 INFO - PROCESS | 25425 | 04:34:39 INFO - PROCESS | 25425 | Cycle 1(1): loaded http://localhost:33230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33230/tests/kraken/driver.html?testName=ai-astar) 04:34:40 INFO - PROCESS | 25425 | RSS: Main: 287698944 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | __start_tp_report 04:34:40 INFO - PROCESS | 25425 | _x_x_mozilla_page_load 04:34:40 INFO - PROCESS | 25425 | _x_x_mozilla_page_load_details 04:34:40 INFO - PROCESS | 25425 | |i|pagename|runs| 04:34:40 INFO - PROCESS | 25425 | |0;ai-astar;110;112;112;111;112;111;111;110;112;111 04:34:40 INFO - PROCESS | 25425 | |1;audio-beat-detection;147;123;123;163;124;126;127;123;145;125 04:34:40 INFO - PROCESS | 25425 | |2;audio-dft;160;161;160;161;159;161;163;161;162;162 04:34:40 INFO - PROCESS | 25425 | |3;audio-fft;74;75;75;106;79;76;76;76;89;75 04:34:40 INFO - PROCESS | 25425 | |4;audio-oscillator;82;83;105;82;82;83;95;84;82;109 04:34:40 INFO - PROCESS | 25425 | |5;imaging-gaussian-blur;117;117;117;118;118;117;117;117;117;117 04:34:40 INFO - PROCESS | 25425 | |6;imaging-darkroom;146;146;145;146;146;146;146;146;147;145 04:34:40 INFO - PROCESS | 25425 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 04:34:40 INFO - PROCESS | 25425 | |8;json-parse-financial;53;51;51;52;53;54;54;53;52;52 04:34:40 INFO - PROCESS | 25425 | |9;json-stringify-tinderbox;62;62;62;61;63;62;62;64;63;65 04:34:40 INFO - PROCESS | 25425 | |10;stanford-crypto-aes;81;81;89;85;80;80;86;80;79;80 04:34:40 INFO - PROCESS | 25425 | |11;stanford-crypto-ccm;136;133;144;131;131;151;132;132;166;138 04:34:40 INFO - PROCESS | 25425 | |12;stanford-crypto-pbkdf2;172;171;169;167;167;169;165;164;164;165 04:34:40 INFO - PROCESS | 25425 | |13;stanford-crypto-sha256-iterative;66;65;66;65;65;65;66;66;65;69 04:34:40 INFO - PROCESS | 25425 | __end_tp_report 04:34:40 INFO - PROCESS | 25425 | __start_cc_report 04:34:40 INFO - PROCESS | 25425 | _x_x_mozilla_cycle_collect,1678 04:34:40 INFO - PROCESS | 25425 | __end_cc_report 04:34:40 INFO - PROCESS | 25425 | __startTimestamp1480422880152__endTimestamp 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | ------- Summary: start ------- 04:34:40 INFO - PROCESS | 25425 | Number of tests: 14 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.50 stddev:0.79 (0.7%) stddev-sans-first:0.71 04:34:40 INFO - PROCESS | 25425 | Values: 110.0 112.0 112.0 111.0 112.0 111.0 111.0 110.0 112.0 111.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:126.50 stddev:14.02 (11.1%) stddev-sans-first:13.87 04:34:40 INFO - PROCESS | 25425 | Values: 147.0 123.0 123.0 163.0 124.0 126.0 127.0 123.0 145.0 125.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.17 04:34:40 INFO - PROCESS | 25425 | Values: 160.0 161.0 160.0 161.0 159.0 161.0 163.0 161.0 162.0 162.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#3] audio-fft Cycles:10 Average:80.10 Median:76.00 stddev:10.09 (13.3%) stddev-sans-first:10.46 04:34:40 INFO - PROCESS | 25425 | Values: 74.0 75.0 75.0 106.0 79.0 76.0 76.0 76.0 89.0 75.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:82.50 stddev:10.46 (12.7%) stddev-sans-first:10.81 04:34:40 INFO - PROCESS | 25425 | Values: 82.0 83.0 105.0 82.0 82.0 83.0 95.0 84.0 82.0 109.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:34:40 INFO - PROCESS | 25425 | Values: 117.0 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:34:40 INFO - PROCESS | 25425 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:34:40 INFO - PROCESS | 25425 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 04:34:40 INFO - PROCESS | 25425 | Values: 53.0 51.0 51.0 52.0 53.0 54.0 54.0 53.0 52.0 52.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:62.50 stddev:1.17 (1.9%) stddev-sans-first:1.22 04:34:40 INFO - PROCESS | 25425 | Values: 62.0 62.0 62.0 61.0 63.0 62.0 62.0 64.0 63.0 65.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:81.00 stddev:3.35 (4.1%) stddev-sans-first:3.53 04:34:40 INFO - PROCESS | 25425 | Values: 81.0 81.0 89.0 85.0 80.0 80.0 86.0 80.0 79.0 80.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:137.00 stddev:11.39 (8.3%) stddev-sans-first:12.02 04:34:40 INFO - PROCESS | 25425 | Values: 136.0 133.0 144.0 131.0 131.0 151.0 132.0 132.0 166.0 138.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:2.87 (1.7%) stddev-sans-first:2.49 04:34:40 INFO - PROCESS | 25425 | Values: 172.0 171.0 169.0 167.0 167.0 169.0 165.0 164.0 164.0 165.0 04:34:40 INFO - PROCESS | 25425 | 04:34:40 INFO - PROCESS | 25425 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.30 04:34:40 INFO - PROCESS | 25425 | Values: 66.0 65.0 66.0 65.0 65.0 65.0 66.0 66.0 65.0 69.0 04:34:40 INFO - PROCESS | 25425 | -------- Summary: end -------- 04:34:40 INFO - PROCESS | 25425 | 04:34:41 INFO - TEST-INFO | 25425: exit 0 04:34:41 INFO - TEST-OK | kraken | took 62647ms 04:34:41 INFO - SUITE-END | took 1181s 04:34:41 INFO - Completed test suite (00:19:42) 04:34:41 INFO - javascript benchmark 04:34:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3066.8773249070036, 3070.723634872861, 3073.0473553198526, 3093.1803072185876, 3118.391913576852, 5171.715555644438, 5227.9738601307035, 5253.106971814791, 5341.492558206969, 5391.91912121318, 5248.291480650111, 5268.341457317835, 5272.23552584875, 5274.208868669702, 5277.762500687469, 4756.571908528232, 4767.404074490689, 4785.641076919228, 4798.712077275374, 4814.542618451242, 4831.08209440207, 4858.246971719389, 4883.706977581339, 4888.315635810989, 4889.608831293505, 4828.517148285161, 4862.173430516803, 4866.951330486685, 4887.828925987574, 4888.315635810989, 4891.804327826882, 4895.755212239392, 4897.583705385011, 4910.779014944336, 4959.479254678243, 4690.742009189493, 4717.599004084657, 4721.811127554928, 4818.277258411232, 4844.20070688336, 5245.239440281176, 5301.655392399482, 5308.628396012281, 5330.1205301125265, 5333.280007199017, 5676.971615141898, 5693.20295158674, 5694.601377903548, 5713.0, 5715.914261286084, 5484.61607687462, 5565.026120428909, 5739.110437882135, 5743.167240750068, 5751.683657398841, 5153.665011774223, 5201.817936372209, 5202.557782588485, 5213.765380557884, 5314.0700377433795, 5738.368779434259, 5747.425257474303, 5759.510441612426, 5792.478676919098, 5803.448672376117, 5496.31296087989, 5505.421930697299, 5513.255710479114, 5515.0, 5522.337319521683, 5411.56707463402, 5510.752016159283, 5520.0, 5624.465675760796, 5626.746796394172, 5569.303837020372, 5595.468430499096, 5598.244237027949, 5601.915971260434, 5634.830955071355, 5587.608867379245, 5587.888242235133, 5603.859903502445, 5606.411326810708, 5643.604947653625, 4205.936910946339, 4232.386303985905, 4321.092570560218, 4331.047169622678, 4376.868693939187, 3809.866654667129, 3846.442265871489, 3873.4770805941403, 3902.804859757058, 3941.684665226775, 5432.1580155075235, 5447.400785913546, 5449.509544141047, 5455.781768729206, 5455.809046683347, 5677.09166533361, 5706.201131841545, 5723.313202415737, 5727.05503591911, 5765.942340576625, 5256.632035757537, 5327.067763141435, 5343.973280133652, 5396.568274538027, 5410.242566040757, 4835.20219163834, 4848.0, 4855.150348688965, 4870.634702397334, 4892.877678058077, 5876.970615146897, 5894.852628684317, 5903.144044113579, 5939.0, 5939.970300148471, 5476.096444086681, 5492.670439773627, 5576.91634625489, 5590.692511911721, 5670.0, 5809.0, 5814.3604203538425, 5846.327672317568, 5863.853403664943, 5945.3757355477055, 5582.637128586629, 5597.692126933058, 5628.4090170533145, 5633.4084921082695, 5649.604527683187, 5665.093585026376, 5679.659220446786, 5680.091185410314, 5681.545476361798, 5682.232898558642, 5378.946210537844, 5510.090835012178, 5580.609357344947, 5582.413846546055, 5584.301962254718, 5386.13821788512, 5476.507114359727, 5521.282233309644, 5541.806036788693, 5621.100623900156, 5513.283273174462, 5538.141588053858, 5539.723013849372, 5541.058020136506, 5580.665160090408, 5600.355959064596, 5659.207710920557, 5660.179274005329, 5665.433456654301, 5670.489655930986, 5311.229871668509, 5348.786048558014, 5448.673079615236, 5471.316085489314, 5474.753636086306], "value": 5218.7791774134475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.5163911039755, 593.6408472873912, 606.5238787551772, 633.0156606476927, 638.562584629529, 32644.510332345006, 41477.58522414775, 42685.35971960415, 42869.35695964563, 42876.78561607187, 44013.33979990303, 44219.77890110553, 44299.11401771971, 44351.77824110883, 44352.556474435325, 7857.449978501507, 8496.78758031048, 8638.09300023498, 8638.352123590756, 8645.308375329958, 7866.056073271216, 9991.350562213469, 10291.588336466531, 10294.897051029506, 10634.627788027421, 10049.196064314874, 10090.394576325407, 10091.495425228746, 10126.898731012707, 10176.236782241325, 10268.794624107513, 10336.121429678486, 10347.74130646736, 10352.120069794077, 10429.113525350278, 10120.797584048352, 10274.48627568626, 10276.486175691185, 10277.486125693686, 10286.691399258034, 41468.37797433041, 41753.79123104396, 41805.37291940623, 41811.7909410454, 41865.37201941973, 3669.541307336583, 3683.5763887153053, 3704.2591481703766, 3893.0462036801164, 3907.4529565860803, 30798.69201308003, 30867.382652346827, 31073.37853242945, 31085.68914310873, 32421.51367729486, 4103.651189648854, 4131.173765246933, 4269.765162916038, 4360.018995725968, 4399.5380485049245, 336648.0, 337180.0, 337429.0, 337699.0, 338376.0, 58030.709846450496, 58390.0, 58391.0, 58754.0, 58802.11796822966, 62649.68675156595, 62665.68667156635, 62716.37283627105, 62792.0, 62825.0, 15813.920930395274, 16402.507924762278, 16409.6718065638, 16433.589160271087, 16460.917695411445, 11258.38078905668, 11600.593979210687, 12181.756364872654, 12635.115541912162, 12666.36668166588, 17629.471115866545, 17752.822471775376, 18154.546136346697, 18299.176537055864, 18331.908340458212, 6043.906675851339, 6087.147799308101, 6139.938600614026, 6159.6612186330185, 6170.321264660884, 922.3912217936264, 1003.7603559603883, 1010.1413798271558, 1104.1056744037355, 1109.20691705431, 30464.84767576148, 31739.36521269562, 31837.88567400176, 32027.199320017186, 32270.35459290848, 1061.4056128568027, 1084.1651928015383, 1105.2263415608945, 1105.4030823355458, 1190.922590031645, 16991.49025529236, 17010.829891700923, 17017.149142542825, 17035.48893533196, 17139.571510712332, 458833.0, 459102.0, 461784.0, 462578.0, 464241.0, 55923.440765591826, 55981.96162345409, 55990.44009559934, 56002.43997559972, 56026.71986640041, 1212.6059030814845, 1225.8345123408226, 1303.4069498378215, 1305.5169587252776, 1345.9730805383838, 28962.0, 32290.192745181557, 32993.835030824695, 33294.16764580808, 33749.0, 49215.75392123016, 49525.0, 49575.50424495853, 49602.0, 49808.25287620616, 366846.0, 368076.0, 371356.5804274466, 371367.0, 371797.0, 27089.1873243803, 28298.43403131967, 28341.0, 29352.55971160477, 29532.261693457836, 46286.53713462822, 46482.0, 47213.763931180125, 47255.29117063178, 48349.758251209925, 39125.41311880267, 39463.60536394599, 39706.0, 40149.397759033054, 41264.79367603263, 39011.2197756049, 39313.80343098266, 39331.2133757329, 39715.0, 41040.58959410487, 39440.60559394484, 39925.60074399335, 40243.3963490542, 40539.79730101331, 40898.0, 34056.48915266323, 35513.64486355103, 36793.816030919676, 36842.26315473622, 36901.44647830338, 27514.44971100607, 27682.9097598528, 27772.0, 27888.58167127535, 28012.85993570019], "value": 19886.894202030246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.12425185539877, 368.90961714379984, 380.79858047859005, 386.86266375436725, 403.209708970418, 15214.467493637725, 15302.464413745507, 18592.535186620342, 19096.713549296735, 19124.23503059878, 19068.427947161574, 19133.61732765344, 19201.903990480063, 19355.903220483913, 19362.51593710158, 4187.74873507589, 5395.73021348933, 5639.492445679888, 5640.887182256352, 5642.74607642655, 4147.723298594122, 6143.385661433843, 6286.968565157168, 6332.84167895804, 6376.617402955814, 5957.642541447505, 6211.285702144243, 6321.24145102586, 6330.240371155444, 6333.176687030679, 6259.593126446789, 6274.403931626488, 6281.905771413432, 6288.402601752826, 6300.0, 5431.728413579305, 5593.0, 5649.435056494359, 5654.038813401731, 5671.291088613923, 13815.723685526233, 13999.720005599831, 14048.297585120703, 14128.92935535326, 14254.92872535641, 2262.400463877074, 2289.473421113153, 2482.36981454187, 2870.937753031371, 2948.7493563047165, 10088.495575221283, 10457.111145552637, 10486.842697359545, 11829.408529573488, 14897.627559310995, 1869.775626924764, 1872.8876267423998, 1911.9808801911943, 1924.3457224543722, 1933.5552822850823, 67932.94301756432, 70204.6489767553, 78625.60687196585, 82594.0, 84253.0, 10289.639862604772, 10634.946825265979, 10990.725231869108, 11352.262102963281, 11877.406129693481, 14790.63023424423, 14810.703785924221, 14830.40678372853, 14847.183404912834, 14849.183294918883, 7801.6879324827505, 7908.2091790821605, 8062.0, 8095.595220238964, 8184.918150818535, 7548.282913123245, 7562.697492100255, 7574.204708505637, 7788.376929845599, 7827.1390147083775, 4576.153411618795, 4580.541945805393, 4656.278276867091, 4713.3401323814005, 4869.707817530889, 1923.4133589255132, 1945.1830231302886, 1949.5905859769323, 1957.7944315846917, 1965.1746266568075, 944.9858252126223, 994.4878387630376, 1011.0, 1023.5036007536339, 1059.655611926127, 19366.4190074298, 19462.416127516197, 21083.894580526998, 21164.36506904795, 21333.67999480009, 979.5885727994117, 990.841465365538, 1017.730301470111, 1027.6968294353185, 1041.5000799616228, 10314.638987635397, 11091.279066860629, 11128.721781955515, 11172.709594533102, 11201.271917325348, 325930.0, 326200.0, 421911.2750832366, 426638.0, 430346.0, 91092.0, 93003.53498232465, 95525.0, 95822.52088739652, 96204.0, 1000.7748256642197, 1020.4591566469783, 1023.3501726403787, 1061.315451533772, 1062.7502536903821, 17268.0, 17665.15423340488, 18193.45419637413, 18267.269309228013, 18437.26250949947, 43122.137557249305, 43161.35257971196, 43339.78330108456, 43853.78073109742, 44935.77532112318, 58092.0, 58573.414265856794, 59027.0, 59161.70419147877, 59189.40810591839, 7933.325667318342, 8172.427930045078, 8390.28682561989, 8427.367947404091, 8582.09887961755, 10175.949120254352, 10861.565537378416, 11246.437678115897, 11311.208215424842, 12099.758004839678, 9571.760705982406, 10181.84727229107, 10182.745431364276, 10447.94776026115, 10987.890121098686, 10574.2598018138, 10940.39827809478, 10994.395308258123, 11031.117510599293, 11031.503582338653, 10489.423081730578, 10513.526891290063, 10613.0, 10615.31000484966, 11048.281861678965, 8154.2253485918745, 8202.753917382488, 8270.297024752723, 8603.397762156588, 8662.78343041429, 10245.231607629308, 10594.205434592282, 10738.033576978109, 10738.19463540222, 10987.230893837355], "value": 8958.048971705335, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.9352032398378, 1432.1621851217035, 1480.1341215388998, 1571.9685606287874, 1736.9044702541355, 6973.790786276413, 7979.84040319194, 8229.794255143624, 8235.464694794835, 8431.70489032884, 8212.178782121784, 8280.710175143871, 8284.378671599625, 8290.212429819157, 8292.792680182984, 1936.2351871010942, 1992.7708313543983, 2211.823054155672, 2247.359502541776, 2249.853759505635, 2227.8106360959255, 2287.0965968442456, 2288.3249441414823, 2303.665968434576, 2306.550222706573, 2214.689943407924, 2232.7320721513443, 2236.5574204638, 2239.552089582082, 2240.0143936667896, 2292.518571100071, 2299.0344055496566, 2309.191782875997, 2309.642005489135, 2311.8266130040147, 2149.602323570129, 2244.0, 2244.876531790751, 2249.876256805875, 2252.526969336425, 8159.347252219808, 8233.17668233178, 8238.09380968093, 8238.876416853693, 8241.587920604006, 4830.130576496229, 4990.6257030722845, 4999.4250661174065, 5000.599952003831, 5001.574866136383, 3104.286014216743, 3227.306129182237, 3298.802071875695, 3309.4043072246986, 3325.3183097465203, 928.0, 1014.1937159957852, 1025.318163421324, 1027.4708525109575, 1071.5124618298655, 10963.50664220112, 10987.780244395148, 10992.725181870437, 10997.12023038155, 11008.174386921013, 4283.807228674717, 4339.522652508222, 4391.231534481517, 4410.779461026936, 4442.666799989949, 9599.904000960041, 9616.75958101039, 9624.75938101553, 9629.470379129216, 9631.277654175965, 4824.445188803333, 4846.466888642247, 4863.683860549053, 4884.902301953941, 4889.339939108246, 4499.505054444008, 4513.7066090704, 4522.027764030683, 4562.0, 4626.167289887852, 3632.54593175853, 3670.908227294339, 3728.8322025508373, 3754.4368344748505, 3774.962250377516, 1184.662371224197, 1194.5938380950345, 1197.5988044005182, 1218.250775772911, 1234.950601975929, 886.8403687336213, 893.2853717026353, 904.0552622509493, 920.9999999999866, 925.9212966897754, 5566.526845218121, 5697.9430205698245, 5729.138030140138, 5730.0, 5804.448577385142, 925.6945208081318, 940.7883226274171, 950.7290422229631, 963.9662611808553, 966.087047739879, 3870.0952608237244, 3946.072672921861, 3967.662748666338, 3997.920041599152, 4078.877633670995, 10007.249456290807, 10156.441395723306, 10252.948735256276, 10254.128399086012, 10268.0, 9567.952160239154, 9938.652147174815, 9942.602295908082, 10124.34191777532, 10188.796224075626, 7669.501482403626, 7844.921550784419, 7846.058472983279, 7855.528668280036, 7856.646450909723, 5535.335759708861, 5620.494155526022, 5691.658500489983, 5718.964854379603, 5900.380460051632, 9117.316201284797, 9171.312151588523, 9202.585883635118, 9214.769630759016, 9240.768980775534, 8755.781105472413, 9060.592273347584, 9078.682246121618, 9270.212031977357, 9286.72139835806, 6301.7164227608955, 6467.838304042437, 6962.1297337832775, 7119.8932016020835, 7205.243449437943, 7817.648205830637, 7844.803879903048, 7858.528488290721, 7889.566073865993, 7927.167647397171, 7166.964165179141, 7199.496035277481, 7222.09723784527, 7381.077365329334, 7450.664720087718, 7041.049458323061, 7051.471139664443, 7134.250903655042, 7154.463415243774, 7196.388306993964, 6424.486041116793, 6518.2504012037325, 6524.543281970216, 6524.706388212637, 6525.0, 4640.3735495709, 4719.315699223662, 4729.810807567659, 4753.595944344769, 4760.619150468024, 4674.322223277538, 4727.432708075053, 4758.262469317261, 4901.828436004866, 4913.852584422473], "value": 4356.816199695632, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.9220194951262, 326.7239182890458, 328.477720424525, 330.61153145054556, 331.07629713100454, 5178.145605975019, 5202.739863006854, 5286.814961476349, 5295.364556253246, 5306.48765101946, 5221.39953905301, 5230.163173892178, 5234.345706786652, 5237.240600112981, 5243.0, 4068.206699693561, 4071.1043570414463, 4087.690454892976, 4089.8364065437345, 4119.917601647965, 3932.842686292545, 3954.1893911748166, 4034.616711412411, 4049.372347286175, 4121.433855135059, 4081.653059489947, 4104.035551645361, 4106.1992911382295, 4117.75293482392, 4121.319982202933, 4094.897627559305, 4095.651869591088, 4105.281575724266, 4109.116539943926, 4109.198706252282, 3367.276035652322, 3372.915677108092, 3407.505911642798, 3610.0, 3627.9818600907092, 5102.438731739506, 5114.130597798383, 5115.641905066647, 5141.820036298712, 5151.278820965068, 4657.138429390574, 4673.158831410345, 4675.625949923998, 4693.202155633517, 4695.295705644146, 5347.358317001985, 5411.295441935817, 5441.0, 5483.314585676791, 5523.088690366125, 3829.349010668193, 3832.2143960488033, 3836.5971572984713, 3844.961550384516, 3854.9229015419814, 5598.356189038272, 5602.7758889644365, 5605.915911261334, 5614.382417934024, 5683.749575093473, 3909.2181563687377, 4037.5760545142925, 4072.511298644182, 4094.488188976378, 4099.713020088625, 4122.896927576835, 4134.855280065183, 4137.358709399988, 4138.52406973196, 4151.7924103794685, 4698.577128058423, 4703.576678098988, 4707.811687532529, 4714.976425117852, 4723.6065360717985, 5423.511883930465, 5523.760402305108, 5533.944660553423, 5569.693666848281, 5573.637713548687, 1363.8431580368185, 1391.5110831410495, 1392.1855714407186, 1392.4430227908715, 1394.2122700674088, 915.6383228624655, 938.7981583959481, 953.6137864165024, 968.2399316536485, 970.6069042037985, 5102.33669622954, 5106.66806657566, 5129.640365431943, 5133.897322053613, 5136.994858008107, 5328.401479556195, 5392.973035134799, 5413.756380962866, 5419.430959749169, 5511.807086751945, 5336.839894803162, 5365.463453654604, 5368.436314186954, 5368.651037682538, 5399.811006614828, 2887.826730396141, 2897.942460852776, 2899.8985035523656, 2917.5040243158714, 2922.517784565523, 5512.719931640748, 5813.168851811751, 5898.84972430372, 5921.0, 5928.110783382441, 5757.165211044375, 5801.651900885876, 5824.504917082096, 5841.269841269841, 5846.736896839652, 5628.887422251614, 5823.2429784128635, 5830.7959221427045, 5936.821895343147, 5950.19672344228, 5691.4877661010705, 5694.4590263924865, 5712.11462223356, 5719.113537401709, 5742.626729262584, 5561.805336813192, 5594.496495315441, 5605.887882242251, 5668.2995101470215, 5677.971610141923, 5643.943560564342, 5829.562782791223, 5855.741015681649, 5871.970640146772, 5874.206982057368, 4043.2924238258774, 4048.40088165179, 4056.7565946043333, 4056.9188616228103, 4076.2255171518505, 5373.313343328336, 5700.25896633435, 5700.344460386943, 5708.828735137952, 5711.771529138788, 5542.5565954722715, 5567.359753628384, 5576.609637325349, 5576.888462230651, 5592.197305831911, 5537.972310138423, 5543.364707411404, 5552.583556233218, 5560.805371812129, 5569.44305569456, 5225.32789507354, 5422.403535611158, 5495.2083062686615, 5508.889822203613, 5518.751656175402, 5541.667499950016, 5570.721463926707, 5573.944260557342, 5578.525825304894, 5580.274564306615, 5234.371875374979, 5297.33134062769, 5371.355437347544, 5471.972640136773, 5473.507384335429], "value": 4192.020500589285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.355396589104, 731.0751898847955, 757.8787394016958, 779.1948983658829, 819.0949001353506, 15609.921950390246, 16554.66890662186, 16564.420245291403, 16740.49778506645, 16891.0, 15065.6986860263, 15075.472358467456, 15392.307346169422, 15404.922975385136, 15407.07557546548, 773.9729109481169, 793.5675057093869, 796.2037962037962, 796.410656114474, 798.1539568057848, 675.0953722012503, 675.2403546010738, 676.9458443324547, 683.0880774166494, 779.3726050529322, 817.4849844597895, 829.1915382502073, 831.9126491718373, 836.6946064686376, 836.9874451883227, 823.0945959444623, 823.7281697039992, 823.7652269103337, 824.2540500846713, 824.6206744897354, 517.1183132758675, 599.379642070455, 599.6641880546907, 599.733118762151, 602.713710987282, 12889.09776315649, 13945.0, 14717.705645887023, 14738.852611473856, 15220.086794792347, 14594.927025364805, 14693.485727999469, 14968.251587420586, 15005.399784008627, 15031.173285469295, 11715.648530544097, 11855.46650400734, 11886.465109070112, 12135.332556709378, 12334.568290109803, 3721.2371463849845, 3829.3872980323295, 3883.514560679915, 3981.5023122109737, 3984.0, 78923.0, 80428.59785701035, 81114.59442702807, 82345.0, 82817.0, 10813.36853115499, 10910.072643825206, 10953.780924381468, 11203.271787333797, 11260.887391126147, 21397.14411423557, 21436.892815535823, 21456.785432145793, 21517.462063448227, 21608.0, 7901.565413902175, 8028.839423211503, 8076.152004039492, 8107.4324797263625, 8151.30713889326, 4253.978730106392, 4255.234057869613, 4320.308750599952, 4347.5652434756275, 4423.336499525097, 3518.0677120563073, 3632.164602141522, 3654.488371627974, 3660.743747937619, 3662.6520480554304, 591.8461200088008, 603.8943184942706, 613.7330261336333, 614.4163045107166, 618.5793660310942, 629.6662768732579, 664.2726214794792, 695.8636984457768, 726.5459088069956, 742.2836962331376, 9558.0, 9815.407432476108, 10025.19798416125, 10184.439855808001, 10322.535485903152, 669.5948950884645, 683.2689022745615, 698.2563569798149, 702.3082263969985, 716.9569825810468, 4413.602775750198, 4789.544993225638, 4839.128956787812, 4974.920769595503, 5017.397912250481, 83345.22805423984, 83706.58146709349, 85073.57463212645, 86637.0, 88281.0, 73214.63392683108, 73391.0, 73435.0, 74737.0, 75253.62373188099, 68615.0, 68845.31154688488, 68977.96533052008, 69272.6536367325, 71169.0, 10032.147267482345, 10262.77756532195, 10308.32995855267, 10478.30768003833, 10500.422476763852, 45119.32321015122, 45376.54623453723, 45895.770521147184, 46008.0, 47482.0, 58196.4180358191, 62893.503014065784, 64406.67796660987, 66002.66998664977, 66055.669721653, 13013.674658133623, 13370.732585348433, 13390.062695611603, 13663.316834158053, 13689.383977720827, 14401.0, 15266.31301591409, 15349.23253837326, 15433.922830386226, 15888.2055897207, 13728.862711373158, 13844.377003034686, 13917.304134793423, 14967.850321496646, 15130.319135638703, 13526.526571569948, 13996.860031399963, 14306.0, 14379.111102225294, 14596.489122880648, 13859.149347271741, 13882.09766365183, 14121.2233327168, 14485.85514144845, 14815.703685926006, 11476.942615286871, 11673.357965311989, 11773.445857601359, 12068.155229133876, 12143.21069130539, 11124.332540047622, 12249.56841440196, 12487.375631218221, 12826.23042617446, 12911.418986145834], "value": 7361.824909098586, "unit": "score"}], "name": "dromaeo_css", "value": 7071.243442021607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 111.0, 112.0, 111.0, 111.0, 110.0, 112.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 123.0, 123.0, 163.0, 124.0, 126.0, 127.0, 123.0, 145.0, 125.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 159.0, 161.0, 163.0, 161.0, 162.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 106.0, 79.0, 76.0, 76.0, 76.0, 89.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 105.0, 82.0, 82.0, 83.0, 95.0, 84.0, 82.0, 109.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "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, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 52.0, 53.0, 54.0, 54.0, 53.0, 52.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 63.0, 62.0, 62.0, 64.0, 63.0, 65.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 89.0, 85.0, 80.0, 80.0, 86.0, 80.0, 79.0, 80.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 144.0, 131.0, 131.0, 151.0, 132.0, 132.0, 166.0, 138.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 169.0, 167.0, 167.0, 169.0, 165.0, 164.0, 164.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 69.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1501.5, "alertThreshold": 2.0}]} 04:34:41 INFO - Return code: 0 04:34:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:34:41 INFO - # TBPL SUCCESS # 04:34:41 INFO - Running post-action listener: _resource_record_post_action 04:34:41 INFO - [mozharness: 2016-11-29 12:34:41.979486Z] Finished run-tests step (success) 04:34:41 INFO - Running post-run listener: _resource_record_post_run 04:34:42 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 405200896; Read time: 32; Write time: 560800 04:34:42 INFO - TinderboxPrint: CPU usage
13.1% 04:34:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 32 04:34:42 INFO - TinderboxPrint: I/O write bytes / time
405,200,896 / 560,800 04:34:42 INFO - TinderboxPrint: CPU idle
8,312.8 (86.9%) 04:34:42 INFO - TinderboxPrint: CPU user
1,201.0 (12.6%) 04:34:42 INFO - TinderboxPrint: Swap in / out
0 / 0 04:34:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114532352; Read time: 0; Write time: 124408 04:34:42 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289669120; Read time: 20; Write time: 436332 04:34:42 INFO - Running post-run listener: _upload_blobber_files 04:34:42 INFO - Blob upload gear active. 04:34:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:34:42 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 04:34:42 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:34:42 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:34:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:34:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:34:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:46 INFO - (blobuploader) - INFO - Done attempting. 04:34:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:47 INFO - (blobuploader) - INFO - Done attempting. 04:34:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:34:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:34:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:48 INFO - (blobuploader) - INFO - Done attempting. 04:34:48 INFO - (blobuploader) - INFO - Iteration through files over. 04:34:48 INFO - Return code: 0 04:34:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:34:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:34:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84596db863326b9f267562556836177189c85d186bcdefc22a6b7a1af284c471ae8887f4d2399f951418499ba98cf219088e20dc86b8d74f34484a503ca66fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b65e72d53ca5978941345b52578f944653ed1661ae59ccd6ebccd2fc667a8ba7f2973c7aa3079e3930df53be3db60840c98168363037f8f835f0ecd6e7e1af60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4f9acbf761fcd345b0577f7ed938095b4f9986081126a326a3b04d843abe5e17d88fe9a4ad67a30c8a61106a19c17b86ead3d63da49c79a311d905f21c4b4"} 04:34:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:34:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:34:48 INFO - Contents: 04:34:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84596db863326b9f267562556836177189c85d186bcdefc22a6b7a1af284c471ae8887f4d2399f951418499ba98cf219088e20dc86b8d74f34484a503ca66fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b65e72d53ca5978941345b52578f944653ed1661ae59ccd6ebccd2fc667a8ba7f2973c7aa3079e3930df53be3db60840c98168363037f8f835f0ecd6e7e1af60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4f9acbf761fcd345b0577f7ed938095b4f9986081126a326a3b04d843abe5e17d88fe9a4ad67a30c8a61106a19c17b86ead3d63da49c79a311d905f21c4b4"} 04:34:48 INFO - Running post-run listener: copy_logs_to_upload_dir 04:34:48 INFO - Copying logs to upload dir... 04:34:48 INFO - mkdir: /builds/slave/test/build/upload/logs 04:34:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.805398 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-29 04:34:47.658331) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 04:34:47.662051) ========= 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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84596db863326b9f267562556836177189c85d186bcdefc22a6b7a1af284c471ae8887f4d2399f951418499ba98cf219088e20dc86b8d74f34484a503ca66fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b65e72d53ca5978941345b52578f944653ed1661ae59ccd6ebccd2fc667a8ba7f2973c7aa3079e3930df53be3db60840c98168363037f8f835f0ecd6e7e1af60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4f9acbf761fcd345b0577f7ed938095b4f9986081126a326a3b04d843abe5e17d88fe9a4ad67a30c8a61106a19c17b86ead3d63da49c79a311d905f21c4b4"} build_url:https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/V7J-85xnTYGTYYE3la5PIQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84596db863326b9f267562556836177189c85d186bcdefc22a6b7a1af284c471ae8887f4d2399f951418499ba98cf219088e20dc86b8d74f34484a503ca66fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b65e72d53ca5978941345b52578f944653ed1661ae59ccd6ebccd2fc667a8ba7f2973c7aa3079e3930df53be3db60840c98168363037f8f835f0ecd6e7e1af60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ad4f9acbf761fcd345b0577f7ed938095b4f9986081126a326a3b04d843abe5e17d88fe9a4ad67a30c8a61106a19c17b86ead3d63da49c79a311d905f21c4b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 04:34:47.690426) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:34:47.691278) ========= 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=745a4373a2a16df560f216e000000264-1480421416.176376-1618113868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 04:34:47.772846) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 04:34:47.773547) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 04:34:47.774264) ========= ========= Total master_lag: 0.18 =========