builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1480266638.27 results: success (0) buildid: 20161127063011 builduid: cefea6d14606405db377176f8954b932 revision: c17948ef9b358c38d267032dc7667142e8c08909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.266606) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.267030) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.267368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.284221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.284845) ========= 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=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-27 09:10:40-- 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% 86.7M=0s 2016-11-27 09:10:40 (86.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115533 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.418811) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.419547) ========= 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=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013459 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.472912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:38.473789) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c17948ef9b358c38d267032dc7667142e8c08909 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c17948ef9b358c38d267032dc7667142e8c08909 --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=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-27 09:10:40,874 truncating revision to first 12 chars 2016-11-27 09:10:40,874 Setting DEBUG logging. 2016-11-27 09:10:40,874 attempt 1/10 2016-11-27 09:10:40,874 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c17948ef9b35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 09:10:41,329 unpacking tar archive at: mozilla-inbound-c17948ef9b35/testing/mozharness/ program finished with exit code 0 elapsedTime=0.795795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:39.288923) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:39.289697) ========= script_repo_revision: c17948ef9b358c38d267032dc7667142e8c08909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:39.290681) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:39.291359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 09:10:39.303251) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-11-27 09:10:39.303958) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:41 INFO - MultiFileLogger online at 20161127 09:10:41 in /builds/slave/test 09:10:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 09:10:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:41 INFO - {'append_to_log': False, 09:10:41 INFO - 'base_work_dir': '/builds/slave/test', 09:10:41 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 09:10:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:41 INFO - 'branch': 'Mozilla-Inbound', 09:10:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:41 INFO - 'config_files': ('talos/linux_config.py',), 09:10:41 INFO - 'default_actions': ('clobber', 09:10:41 INFO - 'read-buildbot-config', 09:10:41 INFO - 'download-and-extract', 09:10:41 INFO - 'populate-webroot', 09:10:41 INFO - 'create-virtualenv', 09:10:41 INFO - 'install', 09:10:41 INFO - 'run-tests'), 09:10:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:41 INFO - 'download_minidump_stackwalk': True, 09:10:41 INFO - 'download_symbols': 'ondemand', 09:10:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:41 INFO - 'installer_path': 'installer.exe', 09:10:41 INFO - 'log_level': 'info', 09:10:41 INFO - 'log_name': 'talos', 09:10:41 INFO - 'log_to_console': True, 09:10:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:41 INFO - 'opt_config_files': (), 09:10:41 INFO - 'pip_index': False, 09:10:41 INFO - 'sps_profile': False, 09:10:41 INFO - 'sps_profile_interval': 0, 09:10:41 INFO - 'suite': 'dromaeojs', 09:10:41 INFO - 'system_bits': '32', 09:10:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:41 INFO - 'title': 'talos-linux64-ix-048', 09:10:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:41 INFO - 'use_talos_json': True, 09:10:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:41 INFO - 'work_dir': 'build'} 09:10:41 INFO - [mozharness: 2016-11-27 17:10:41.802181Z] Running clobber step. 09:10:41 INFO - Running pre-action listener: _resource_record_pre_action 09:10:41 INFO - Running main action method: clobber 09:10:41 INFO - rmtree: /builds/slave/test/build 09:10:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:42 INFO - Running post-action listener: _resource_record_post_action 09:10:42 INFO - [mozharness: 2016-11-27 17:10:42.259509Z] Finished clobber step (success) 09:10:42 INFO - [mozharness: 2016-11-27 17:10:42.259607Z] Running read-buildbot-config step. 09:10:42 INFO - Running pre-action listener: _resource_record_pre_action 09:10:42 INFO - Running main action method: read_buildbot_config 09:10:42 INFO - Using buildbot properties: 09:10:42 INFO - { 09:10:42 INFO - "project": "", 09:10:42 INFO - "product": "firefox", 09:10:42 INFO - "script_repo_revision": "production", 09:10:42 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 09:10:42 INFO - "repository": "", 09:10:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 09:10:42 INFO - "buildid": "20161127063011", 09:10:42 INFO - "pgo_build": "True", 09:10:42 INFO - "basedir": "/builds/slave/test", 09:10:42 INFO - "buildnumber": 2162, 09:10:42 INFO - "slavename": "talos-linux64-ix-048", 09:10:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:10:42 INFO - "platform": "ubuntu64_hw", 09:10:42 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 09:10:42 INFO - "branch": "mozilla-inbound", 09:10:42 INFO - "repo_path": "integration/mozilla-inbound", 09:10:42 INFO - "revision": "c17948ef9b358c38d267032dc7667142e8c08909", 09:10:42 INFO - "stage_platform": "linux64-pgo", 09:10:42 INFO - "builduid": "cefea6d14606405db377176f8954b932", 09:10:42 INFO - "slavebuilddir": "test" 09:10:42 INFO - } 09:10:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:10:42 INFO - Running post-action listener: _resource_record_post_action 09:10:42 INFO - [mozharness: 2016-11-27 17:10:42.262015Z] Finished read-buildbot-config step (success) 09:10:42 INFO - [mozharness: 2016-11-27 17:10:42.262103Z] Running download-and-extract step. 09:10:42 INFO - Running pre-action listener: _resource_record_pre_action 09:10:42 INFO - Running main action method: download_and_extract 09:10:42 INFO - mkdir: /builds/slave/test/build/tests 09:10:42 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')]} 09:10:42 INFO - trying https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:10:42 INFO - Downloading https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/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 09:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/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 09:10:42 INFO - Downloaded 1361 bytes. 09:10:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:10:42 INFO - Using the following test package requirements: 09:10:42 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:10:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:10:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:10:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'jsshell-linux-x86_64.zip'], 09:10:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:10:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:10:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:10:42 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:10:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:10:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:10:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:10:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:10:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:10:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:10:42 INFO - Fetch https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:10:43 INFO - Http code: 200 09:10:43 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 09:10:43 INFO - x-amz-cf-id: 2LQkMstgm_OQeQ_YT2NBasp_DXheBPeES8nI9kX4j44nEE4H_83KnQ== 09:10:43 INFO - x-amz-version-id: XgMfjrkdjB1CzDpJsuOv2rcZSLbcR3Oo 09:10:43 INFO - Expected file size: 21780563 09:10:43 INFO - Obtained file size: 21780563 09:10:47 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 09:10:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:10:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:10:47 INFO - Fetch https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:10:48 INFO - Http code: 200 09:10:48 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:10:48 INFO - x-amz-cf-id: rN2MkV2L2KFRXWe0z5jUAu3XTsuMLVWxr0HejAWsUZhAjkX-u2g-Dg== 09:10:48 INFO - x-amz-version-id: XgMfjrkdjB1CzDpJsuOv2rcZSLbcR3Oo 09:10:48 INFO - Expected file size: 21780563 09:10:48 INFO - Obtained file size: 21780563 09:10:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:10:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:10:52 INFO - Fetch https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:10:53 INFO - Http code: 200 09:10:53 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 09:10:53 INFO - x-amz-cf-id: SJxs4Za1qRjllsFfvXfrTSe2cr7tTEEstmVppYWbnvMKa2XOfNefhQ== 09:10:53 INFO - x-amz-version-id: .cZ1p7tRqnJRKN0sCnTIJngcr9Qrqij0 09:10:53 INFO - Expected file size: 14409702 09:10:53 INFO - Obtained file size: 14409702 09:10:53 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')]} 09:10:53 INFO - trying https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:53 INFO - Downloading https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/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 09:10:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/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 09:10:54 INFO - Downloaded 59817985 bytes. 09:10:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:54 INFO - mkdir: /builds/slave/test/properties 09:10:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:54 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:54 INFO - Contents: 09:10:54 INFO - build_url:https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:55 INFO - Running post-action listener: _resource_record_post_action 09:10:55 INFO - Running post-action listener: set_extra_try_arguments 09:10:55 INFO - [mozharness: 2016-11-27 17:10:55.547723Z] Finished download-and-extract step (success) 09:10:55 INFO - [mozharness: 2016-11-27 17:10:55.547946Z] Running populate-webroot step. 09:10:55 INFO - Running pre-action listener: _resource_record_pre_action 09:10:55 INFO - Running main action method: populate_webroot 09:10:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:55 INFO - '--tppagecycles', 09:10:55 INFO - '7'], 09:10:55 INFO - 'tests': ['tsvgm']}}, 09:10:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['damp', 'tps']}, 09:10:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'tests': ['damp', 'tps']}, 09:10:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['dromaeo_dom']}, 09:10:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['basic_compositor_video']}, 09:10:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:10:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['a11yr', 09:10:55 INFO - 'ts_paint', 09:10:55 INFO - 'tpaint', 09:10:55 INFO - 'sessionrestore', 09:10:55 INFO - 'sessionrestore_no_auto_restore', 09:10:55 INFO - 'tabpaint']}, 09:10:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['tsvgx', 09:10:55 INFO - 'tsvgr_opacity', 09:10:55 INFO - 'tart', 09:10:55 INFO - 'tscrollx', 09:10:55 INFO - 'cart', 09:10:55 INFO - 'tsvg_static']}, 09:10:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:55 INFO - 'tsvgr_opacity', 09:10:55 INFO - 'tart', 09:10:55 INFO - 'tscrollx', 09:10:55 INFO - 'cart', 09:10:55 INFO - 'tsvg_static']}, 09:10:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'talos_options': ['--disable-e10s'], 09:10:55 INFO - 'tests': ['tp5o']}, 09:10:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:10:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'talos_options': ['--disable-e10s', 09:10:55 INFO - '--xperf_path', 09:10:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:55 INFO - 'tests': ['tp5n']}, 09:10:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:10:55 INFO - 'talos_options': ['--xperf_path', 09:10:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:55 INFO - 'tests': ['tp5n']}}, 09:10:55 INFO - 'talos.zip': {'path': '', 09:10:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:55 INFO - Running post-action listener: _resource_record_post_action 09:10:55 INFO - [mozharness: 2016-11-27 17:10:55.569567Z] Finished populate-webroot step (success) 09:10:55 INFO - [mozharness: 2016-11-27 17:10:55.569654Z] Running create-virtualenv step. 09:10:55 INFO - Running pre-action listener: _resource_record_pre_action 09:10:55 INFO - Running main action method: create_virtualenv 09:10:55 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:55 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 09:10:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:55 INFO - Using real prefix '/usr' 09:10:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:56 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:58 INFO - Installing pip.................done. 09:10:58 INFO - Return code: 0 09:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:10:58 INFO - Reading from file tmpfile_stdout 09:10:58 INFO - Output received: 09:10:58 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) 09:10:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:10:58 INFO - Using env: {'DISPLAY': ':0', 09:10:58 INFO - 'HOME': '/home/cltbld', 09:10:58 INFO - 'LANG': 'en_US.UTF-8', 09:10:58 INFO - 'LANGUAGE': 'en_US:en', 09:10:58 INFO - 'LOGNAME': 'cltbld', 09:10:58 INFO - 'MAIL': '/var/mail/cltbld', 09:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:58 INFO - 'MOZ_NO_REMOTE': '1', 09:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:58 INFO - 'NO_EM_RESTART': '1', 09:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:58 INFO - 'PWD': '/builds/slave/test', 09:10:58 INFO - 'SHELL': '/bin/bash', 09:10:58 INFO - 'SHLVL': '1', 09:10:58 INFO - 'TERM': 'linux', 09:10:58 INFO - 'TMOUT': '86400', 09:10:58 INFO - 'USER': 'cltbld', 09:10:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:58 INFO - '_': '/tools/buildbot/bin/python'} 09:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:58 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 09:10:58 INFO - Cleaning up... 09:10:58 INFO - Return code: 0 09:10:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:10:58 INFO - Using env: {'DISPLAY': ':0', 09:10:58 INFO - 'HOME': '/home/cltbld', 09:10:58 INFO - 'LANG': 'en_US.UTF-8', 09:10:58 INFO - 'LANGUAGE': 'en_US:en', 09:10:58 INFO - 'LOGNAME': 'cltbld', 09:10:58 INFO - 'MAIL': '/var/mail/cltbld', 09:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:58 INFO - 'MOZ_NO_REMOTE': '1', 09:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:58 INFO - 'NO_EM_RESTART': '1', 09:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:58 INFO - 'PWD': '/builds/slave/test', 09:10:58 INFO - 'SHELL': '/bin/bash', 09:10:58 INFO - 'SHLVL': '1', 09:10:58 INFO - 'TERM': 'linux', 09:10:58 INFO - 'TMOUT': '86400', 09:10:58 INFO - 'USER': 'cltbld', 09:10:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:58 INFO - '_': '/tools/buildbot/bin/python'} 09:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:58 INFO - Downloading/unpacking psutil>=3.1.1 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:00 INFO - Installing collected packages: psutil 09:11:00 INFO - Running setup.py install for psutil 09:11:00 INFO - building 'psutil._psutil_linux' extension 09:11:00 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 09:11:01 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 09:11:01 INFO - building 'psutil._psutil_posix' extension 09:11:01 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 09:11:01 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 09:11:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:01 INFO - Successfully installed psutil 09:11:01 INFO - Cleaning up... 09:11:01 INFO - Return code: 0 09:11:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:01 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:01 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 09:11:01 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 09:11:01 INFO - Using env: {'DISPLAY': ':0', 09:11:01 INFO - 'HOME': '/home/cltbld', 09:11:01 INFO - 'LANG': 'en_US.UTF-8', 09:11:01 INFO - 'LANGUAGE': 'en_US:en', 09:11:01 INFO - 'LOGNAME': 'cltbld', 09:11:01 INFO - 'MAIL': '/var/mail/cltbld', 09:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:01 INFO - 'MOZ_NO_REMOTE': '1', 09:11:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:01 INFO - 'NO_EM_RESTART': '1', 09:11:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:01 INFO - 'PWD': '/builds/slave/test', 09:11:01 INFO - 'SHELL': '/bin/bash', 09:11:01 INFO - 'SHLVL': '1', 09:11:01 INFO - 'TERM': 'linux', 09:11:01 INFO - 'TMOUT': '86400', 09:11:01 INFO - 'USER': 'cltbld', 09:11:01 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:01 INFO - '_': '/tools/buildbot/bin/python'} 09:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:01 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 09:11:01 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 09:11:01 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 09:11:01 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 09:11:01 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 09:11:01 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 09:11:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:03 INFO - Installing collected packages: mozsystemmonitor 09:11:03 INFO - Running setup.py install for mozsystemmonitor 09:11:03 INFO - Successfully installed mozsystemmonitor 09:11:03 INFO - Cleaning up... 09:11:03 INFO - Return code: 0 09:11:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:03 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')]} 09:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:03 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')]} 09:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:03 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:03 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 09:11:03 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 09:11:03 INFO - Using env: {'DISPLAY': ':0', 09:11:03 INFO - 'HOME': '/home/cltbld', 09:11:03 INFO - 'LANG': 'en_US.UTF-8', 09:11:03 INFO - 'LANGUAGE': 'en_US:en', 09:11:03 INFO - 'LOGNAME': 'cltbld', 09:11:03 INFO - 'MAIL': '/var/mail/cltbld', 09:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:03 INFO - 'MOZ_NO_REMOTE': '1', 09:11:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:03 INFO - 'NO_EM_RESTART': '1', 09:11:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:03 INFO - 'PWD': '/builds/slave/test', 09:11:03 INFO - 'SHELL': '/bin/bash', 09:11:03 INFO - 'SHLVL': '1', 09:11:03 INFO - 'TERM': 'linux', 09:11:03 INFO - 'TMOUT': '86400', 09:11:03 INFO - 'USER': 'cltbld', 09:11:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:03 INFO - '_': '/tools/buildbot/bin/python'} 09:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:04 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 09:11:04 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 09:11:04 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 09:11:04 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 09:11:04 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 09:11:04 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 09:11:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:05 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 09:11:05 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 09:11:05 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 09:11:05 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 09:11:05 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 09:11:05 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 09:11:06 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:06 INFO - warning: no files found matching '*.txt' 09:11:06 INFO - no previously-included directories found matching 'build' 09:11:06 INFO - no previously-included directories found matching 'dist' 09:11:06 INFO - no previously-included directories found matching '.git*' 09:11:06 INFO - Installing collected packages: jsonschema, functools32 09:11:06 INFO - Running setup.py install for functools32 09:11:06 INFO - warning: no files found matching '*.txt' 09:11:06 INFO - no previously-included directories found matching 'build' 09:11:06 INFO - no previously-included directories found matching 'dist' 09:11:06 INFO - no previously-included directories found matching '.git*' 09:11:06 INFO - Successfully installed jsonschema functools32 09:11:06 INFO - Cleaning up... 09:11:06 INFO - Return code: 0 09:11:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:06 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:06 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 09:11:06 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 09:11:06 INFO - Using env: {'DISPLAY': ':0', 09:11:06 INFO - 'HOME': '/home/cltbld', 09:11:06 INFO - 'LANG': 'en_US.UTF-8', 09:11:06 INFO - 'LANGUAGE': 'en_US:en', 09:11:06 INFO - 'LOGNAME': 'cltbld', 09:11:06 INFO - 'MAIL': '/var/mail/cltbld', 09:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:06 INFO - 'MOZ_NO_REMOTE': '1', 09:11:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:06 INFO - 'NO_EM_RESTART': '1', 09:11:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:06 INFO - 'PWD': '/builds/slave/test', 09:11:06 INFO - 'SHELL': '/bin/bash', 09:11:06 INFO - 'SHLVL': '1', 09:11:06 INFO - 'TERM': 'linux', 09:11:06 INFO - 'TMOUT': '86400', 09:11:06 INFO - 'USER': 'cltbld', 09:11:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:06 INFO - '_': '/tools/buildbot/bin/python'} 09:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:06 INFO - Cleaning up... 09:11:06 INFO - Return code: 0 09:11:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:06 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:06 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 09:11:06 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 09:11:06 INFO - Using env: {'DISPLAY': ':0', 09:11:06 INFO - 'HOME': '/home/cltbld', 09:11:06 INFO - 'LANG': 'en_US.UTF-8', 09:11:06 INFO - 'LANGUAGE': 'en_US:en', 09:11:06 INFO - 'LOGNAME': 'cltbld', 09:11:06 INFO - 'MAIL': '/var/mail/cltbld', 09:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:06 INFO - 'MOZ_NO_REMOTE': '1', 09:11:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:06 INFO - 'NO_EM_RESTART': '1', 09:11:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:06 INFO - 'PWD': '/builds/slave/test', 09:11:06 INFO - 'SHELL': '/bin/bash', 09:11:06 INFO - 'SHLVL': '1', 09:11:06 INFO - 'TERM': 'linux', 09:11:06 INFO - 'TMOUT': '86400', 09:11:06 INFO - 'USER': 'cltbld', 09:11:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:06 INFO - '_': '/tools/buildbot/bin/python'} 09:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:06 INFO - Downloading/unpacking blobuploader==1.2.4 09:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:08 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:08 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 09:11:08 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 09:11:08 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 09:11:08 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 09:11:08 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 09:11:08 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 09:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11:09 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 09:11:09 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 09:11:09 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 09:11:09 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 09:11:09 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 09:11:09 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 09:11:09 INFO - Downloading docopt-0.6.1.tar.gz 09:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:09 INFO - Running setup.py install for blobuploader 09:11:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:09 INFO - Running setup.py install for requests 09:11:10 INFO - Running setup.py install for docopt 09:11:10 INFO - Successfully installed blobuploader requests docopt 09:11:10 INFO - Cleaning up... 09:11:10 INFO - Return code: 0 09:11:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:10 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:10 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 09:11:10 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 09:11:10 INFO - Using env: {'DISPLAY': ':0', 09:11:10 INFO - 'HOME': '/home/cltbld', 09:11:10 INFO - 'LANG': 'en_US.UTF-8', 09:11:10 INFO - 'LANGUAGE': 'en_US:en', 09:11:10 INFO - 'LOGNAME': 'cltbld', 09:11:10 INFO - 'MAIL': '/var/mail/cltbld', 09:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:10 INFO - 'MOZ_NO_REMOTE': '1', 09:11:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:10 INFO - 'NO_EM_RESTART': '1', 09:11:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:10 INFO - 'PWD': '/builds/slave/test', 09:11:10 INFO - 'SHELL': '/bin/bash', 09:11:10 INFO - 'SHLVL': '1', 09:11:10 INFO - 'TERM': 'linux', 09:11:10 INFO - 'TMOUT': '86400', 09:11:10 INFO - 'USER': 'cltbld', 09:11:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:10 INFO - '_': '/tools/buildbot/bin/python'} 09:11:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:10 INFO - Running setup.py (path:/tmp/pip-r7Rn9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:10 INFO - Running setup.py (path:/tmp/pip-hqE950-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:10 INFO - Running setup.py (path:/tmp/pip-zAZB5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:10 INFO - Running setup.py (path:/tmp/pip-29g4A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:10 INFO - Running setup.py (path:/tmp/pip-xdvNV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:10 INFO - Running setup.py (path:/tmp/pip-_11pl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:10 INFO - Running setup.py (path:/tmp/pip-2ECfXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:10 INFO - Running setup.py (path:/tmp/pip-A5j8v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:11 INFO - Running setup.py (path:/tmp/pip-YK9Bmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:11 INFO - Running setup.py (path:/tmp/pip-r7ZHGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:11 INFO - Running setup.py (path:/tmp/pip-sauKZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:11 INFO - Running setup.py (path:/tmp/pip-qh0kXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:11 INFO - Running setup.py (path:/tmp/pip-enXI9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:11 INFO - Running setup.py (path:/tmp/pip-GlpmrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:11 INFO - Running setup.py (path:/tmp/pip-ibL9YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:11 INFO - Running setup.py (path:/tmp/pip-Fag94K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:11 INFO - Running setup.py (path:/tmp/pip-pMigtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:11 INFO - Running setup.py install for manifestparser 09:11:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:11 INFO - Running setup.py install for mozcrash 09:11:11 INFO - Running setup.py install for mozdebug 09:11:11 INFO - Running setup.py install for mozdevice 09:11:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for mozfile 09:11:12 INFO - Running setup.py install for mozhttpd 09:11:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for mozinfo 09:11:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for mozInstall 09:11:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for mozleak 09:11:12 INFO - Running setup.py install for mozlog 09:11:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for moznetwork 09:11:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:12 INFO - Running setup.py install for mozprocess 09:11:12 INFO - Running setup.py install for mozprofile 09:11:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:13 INFO - Running setup.py install for mozrunner 09:11:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:13 INFO - Running setup.py install for mozscreenshot 09:11:13 INFO - Running setup.py install for moztest 09:11:13 INFO - Running setup.py install for mozversion 09:11:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:13 INFO - Cleaning up... 09:11:13 INFO - Return code: 0 09:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:13 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')]} 09:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 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')]} 09:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:13 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 09:11:13 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 09:11:13 INFO - Using env: {'DISPLAY': ':0', 09:11:13 INFO - 'HOME': '/home/cltbld', 09:11:13 INFO - 'LANG': 'en_US.UTF-8', 09:11:13 INFO - 'LANGUAGE': 'en_US:en', 09:11:13 INFO - 'LOGNAME': 'cltbld', 09:11:13 INFO - 'MAIL': '/var/mail/cltbld', 09:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:13 INFO - 'MOZ_NO_REMOTE': '1', 09:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:13 INFO - 'NO_EM_RESTART': '1', 09:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:13 INFO - 'PWD': '/builds/slave/test', 09:11:13 INFO - 'SHELL': '/bin/bash', 09:11:13 INFO - 'SHLVL': '1', 09:11:13 INFO - 'TERM': 'linux', 09:11:13 INFO - 'TMOUT': '86400', 09:11:13 INFO - 'USER': 'cltbld', 09:11:13 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:13 INFO - '_': '/tools/buildbot/bin/python'} 09:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:13 INFO - Running setup.py (path:/tmp/pip-syCWYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:13 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)) 09:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:13 INFO - Running setup.py (path:/tmp/pip-7ZaY6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:13 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)) 09:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:13 INFO - Running setup.py (path:/tmp/pip-VVo3fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:13 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)) 09:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:13 INFO - Running setup.py (path:/tmp/pip-0gPnW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:14 INFO - Running setup.py (path:/tmp/pip-9oyuov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:14 INFO - Running setup.py (path:/tmp/pip-sCLLTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:14 INFO - Running setup.py (path:/tmp/pip-mbcMNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:14 INFO - Running setup.py (path:/tmp/pip-zQA6qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:14 INFO - Running setup.py (path:/tmp/pip-7kTi77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:14 INFO - Running setup.py (path:/tmp/pip-PP08AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:14 INFO - Running setup.py (path:/tmp/pip-EPxvA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:14 INFO - Running setup.py (path:/tmp/pip-x11PEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:14 INFO - Running setup.py (path:/tmp/pip-YgcwuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:14 INFO - Running setup.py (path:/tmp/pip-Z0cnQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:14 INFO - Running setup.py (path:/tmp/pip-dKIX5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:14 INFO - Running setup.py (path:/tmp/pip-9_Pil0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:14 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)) 09:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:14 INFO - Running setup.py (path:/tmp/pip-CirQfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:14 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)) 09:11:14 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)) 09:11:14 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)) 09:11:14 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)) 09:11:14 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)) 09:11:14 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)) 09:11:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:14 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 09:11:14 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 09:11:14 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 09:11:14 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 09:11:14 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 09:11:14 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 09:11:16 INFO - Downloading blessings-1.6.tar.gz 09:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:16 INFO - Installing collected packages: blessings 09:11:16 INFO - Running setup.py install for blessings 09:11:17 INFO - Successfully installed blessings 09:11:17 INFO - Cleaning up... 09:11:17 INFO - Return code: 0 09:11:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:17 INFO - Reading from file tmpfile_stdout 09:11:17 INFO - Current package versions: 09:11:17 INFO - argparse == 1.2.1 09:11:17 INFO - blessings == 1.6 09:11:17 INFO - blobuploader == 1.2.4 09:11:17 INFO - docopt == 0.6.1 09:11:17 INFO - functools32 == 3.2.3-2 09:11:17 INFO - jsonschema == 2.5.1 09:11:17 INFO - manifestparser == 1.1 09:11:17 INFO - mozInstall == 1.12 09:11:17 INFO - mozcrash == 1.0 09:11:17 INFO - mozdebug == 0.1 09:11:17 INFO - mozdevice == 0.49 09:11:17 INFO - mozfile == 1.2 09:11:17 INFO - mozhttpd == 0.7 09:11:17 INFO - mozinfo == 0.9 09:11:17 INFO - mozleak == 0.1 09:11:17 INFO - mozlog == 3.4 09:11:17 INFO - moznetwork == 0.27 09:11:17 INFO - mozprocess == 0.23 09:11:17 INFO - mozprofile == 0.28 09:11:17 INFO - mozrunner == 6.13 09:11:17 INFO - mozscreenshot == 0.1 09:11:17 INFO - mozsystemmonitor == 0.3 09:11:17 INFO - moztest == 0.7 09:11:17 INFO - mozversion == 1.4 09:11:17 INFO - psutil == 3.1.1 09:11:17 INFO - requests == 1.2.3 09:11:17 INFO - wsgiref == 0.1.2 09:11:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 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 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:17 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 09:11:17 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 09:11:17 INFO - Using env: {'DISPLAY': ':0', 09:11:17 INFO - 'HOME': '/home/cltbld', 09:11:17 INFO - 'LANG': 'en_US.UTF-8', 09:11:17 INFO - 'LANGUAGE': 'en_US:en', 09:11:17 INFO - 'LOGNAME': 'cltbld', 09:11:17 INFO - 'MAIL': '/var/mail/cltbld', 09:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:17 INFO - 'MOZ_NO_REMOTE': '1', 09:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:17 INFO - 'NO_EM_RESTART': '1', 09:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:17 INFO - 'PWD': '/builds/slave/test', 09:11:17 INFO - 'SHELL': '/bin/bash', 09:11:17 INFO - 'SHLVL': '1', 09:11:17 INFO - 'TERM': 'linux', 09:11:17 INFO - 'TMOUT': '86400', 09:11:17 INFO - 'USER': 'cltbld', 09:11:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:17 INFO - '_': '/tools/buildbot/bin/python'} 09:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 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)) 09:11:17 INFO - Cleaning up... 09:11:17 INFO - Return code: 0 09:11:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f50e6e76e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, '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': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', '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 09:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:11:17 INFO - Using env: {'DISPLAY': ':0', 09:11:17 INFO - 'HOME': '/home/cltbld', 09:11:17 INFO - 'LANG': 'en_US.UTF-8', 09:11:17 INFO - 'LANGUAGE': 'en_US:en', 09:11:17 INFO - 'LOGNAME': 'cltbld', 09:11:17 INFO - 'MAIL': '/var/mail/cltbld', 09:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:17 INFO - 'MOZ_NO_REMOTE': '1', 09:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:17 INFO - 'NO_EM_RESTART': '1', 09:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:17 INFO - 'PWD': '/builds/slave/test', 09:11:17 INFO - 'SHELL': '/bin/bash', 09:11:17 INFO - 'SHLVL': '1', 09:11:17 INFO - 'TERM': 'linux', 09:11:17 INFO - 'TMOUT': '86400', 09:11:17 INFO - 'USER': 'cltbld', 09:11:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:17 INFO - '_': '/tools/buildbot/bin/python'} 09:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:17 INFO - Cleaning up... 09:11:17 INFO - Return code: 0 09:11:17 INFO - Running post-action listener: _resource_record_post_action 09:11:17 INFO - Running post-action listener: _start_resource_monitoring 09:11:17 INFO - Starting resource monitoring. 09:11:17 INFO - [mozharness: 2016-11-27 17:11:17.885924Z] Finished create-virtualenv step (success) 09:11:17 INFO - [mozharness: 2016-11-27 17:11:17.886407Z] Running install step. 09:11:17 INFO - Running pre-action listener: _resource_record_pre_action 09:11:17 INFO - Running main action method: install 09:11:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:18 INFO - Reading from file tmpfile_stdout 09:11:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:18 INFO - Reading from file tmpfile_stdout 09:11:18 INFO - Output received: 09:11:18 INFO - Usage: mozinstall [options] installer 09:11:18 INFO - Options: 09:11:18 INFO - -h, --help show this help message and exit 09:11:18 INFO - -d DEST, --destination=DEST 09:11:18 INFO - Directory to install application into. [default: 09:11:18 INFO - "/builds/slave/test"] 09:11:18 INFO - --app=APP Application being installed. [default: firefox] 09:11:18 INFO - mkdir: /builds/slave/test/build/application 09:11:18 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'] 09:11:18 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 09:11:35 INFO - Reading from file tmpfile_stdout 09:11:35 INFO - Output received: 09:11:35 INFO - /builds/slave/test/build/application/firefox/firefox 09:11:35 INFO - Running post-action listener: _resource_record_post_action 09:11:35 INFO - [mozharness: 2016-11-27 17:11:35.170262Z] Finished install step (success) 09:11:35 INFO - [mozharness: 2016-11-27 17:11:35.170501Z] Running run-tests step. 09:11:35 INFO - Running pre-action listener: _resource_record_pre_action 09:11:35 INFO - Running main action method: run_tests 09:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:35 INFO - Python 2.7.3 09:11:35 INFO - Return code: 0 09:11:35 INFO - grabbing minidump binary from tooltool 09:11:35 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')]} 09:11:35 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 0x2449fc0>, '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 0x244aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244b350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:35 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 09:11:35 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 09:11:35 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 09:11:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:35 INFO - Return code: 0 09:11:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:11:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:11:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 09:11:35 INFO - Using env: {'DISPLAY': ':0', 09:11:35 INFO - 'HOME': '/home/cltbld', 09:11:35 INFO - 'LANG': 'en_US.UTF-8', 09:11:35 INFO - 'LANGUAGE': 'en_US:en', 09:11:35 INFO - 'LOGNAME': 'cltbld', 09:11:35 INFO - 'MAIL': '/var/mail/cltbld', 09:11:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:35 INFO - 'MOZ_NO_REMOTE': '1', 09:11:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:35 INFO - 'NO_EM_RESTART': '1', 09:11:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:35 INFO - 'PWD': '/builds/slave/test', 09:11:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:35 INFO - 'SHELL': '/bin/bash', 09:11:35 INFO - 'SHLVL': '1', 09:11:35 INFO - 'TERM': 'linux', 09:11:35 INFO - 'TMOUT': '86400', 09:11:35 INFO - 'USER': 'cltbld', 09:11:35 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759', 09:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:35 INFO - '_': '/tools/buildbot/bin/python'} 09:11:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:11:35 INFO - mozversion application_buildid: 20161127063011 09:11:35 INFO - mozversion application_changeset: c17948ef9b358c38d267032dc7667142e8c08909 09:11:35 INFO - mozversion application_display_name: Nightly 09:11:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:35 INFO - mozversion application_name: Firefox 09:11:35 INFO - mozversion application_remotingname: firefox 09:11:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:11:35 INFO - mozversion application_vendor: Mozilla 09:11:35 INFO - mozversion application_version: 53.0a1 09:11:35 INFO - mozversion platform_buildid: 20161127063011 09:11:35 INFO - mozversion platform_changeset: c17948ef9b358c38d267032dc7667142e8c08909 09:11:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:11:35 INFO - mozversion platform_version: 53.0a1 09:11:35 INFO - using testdate: 1480266695 09:11:35 INFO - actual date: 1480266695 09:11:35 INFO - starting webserver on 'localhost:38566' 09:11:35 INFO - SUITE-START | Running 2 tests 09:11:35 INFO - TEST-START | dromaeo_css 09:11:35 INFO - operating with platform_type : linux_ 09:11:35 INFO - Initialising browser for dromaeo_css test... 09:11:35 INFO - TEST-INFO | started process 29407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1Bccl/profile http://localhost:38566/getInfo.html) 09:11:39 INFO - PROCESS | 29407 | __metrics Screen width/height:1600/1200 09:11:39 INFO - PROCESS | 29407 | colorDepth:24 09:11:39 INFO - PROCESS | 29407 | Browser inner width/height: 1024/697 09:11:39 INFO - PROCESS | 29407 | __metrics 09:11:40 INFO - PROCESS | 29407 | 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" 09:11:40 INFO - PROCESS | 29407 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:11:41 INFO - PROCESS | 29407 | console.error: PushService: 09:11:41 INFO - PROCESS | 29407 | stateChangeProcessEnqueue: Error transitioning state 09:11:41 INFO - PROCESS | 29407 | UnknownError 09:11:41 INFO - TEST-INFO | 29407: exit 0 09:11:41 INFO - Browser initialized. 09:11:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:41 INFO - TEST-INFO | started process 29489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1Bccl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:53 INFO - PROCESS | 29489 | RSS: Main: 173600768 09:11:53 INFO - PROCESS | 29489 | 09:12:45 INFO - PROCESS | 29489 | 1480266765780 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] 09:14:55 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38566/tests/dromaeo/cssquery-ext.html) 09:14:58 INFO - PROCESS | 29489 | RSS: Main: 384495616 09:14:58 INFO - PROCESS | 29489 | 09:18:00 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-ext.html (next: http://localhost:38566/tests/dromaeo/cssquery-jquery.html) 09:18:01 INFO - PROCESS | 29489 | RSS: Main: 288759808 09:18:01 INFO - PROCESS | 29489 | 09:21:03 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38566/tests/dromaeo/cssquery-mootools.html) 09:21:03 INFO - PROCESS | 29489 | RSS: Main: 224481280 09:21:03 INFO - PROCESS | 29489 | 09:24:05 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38566/tests/dromaeo/cssquery-prototype.html) 09:24:06 INFO - PROCESS | 29489 | RSS: Main: 264863744 09:24:06 INFO - PROCESS | 29489 | 09:27:09 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38566/tests/dromaeo/cssquery-yui.html) 09:27:09 INFO - PROCESS | 29489 | RSS: Main: 222547968 09:27:09 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | Cycle 1(1): loaded http://localhost:38566/tests/dromaeo/cssquery-yui.html (next: http://localhost:38566/tests/dromaeo/cssquery-dojo.html) 09:30:12 INFO - PROCESS | 29489 | RSS: Main: 312549376 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | __start_tp_report 09:30:12 INFO - PROCESS | 29489 | _x_x_mozilla_page_load 09:30:12 INFO - PROCESS | 29489 | _x_x_mozilla_page_load_details 09:30:12 INFO - PROCESS | 29489 | |i|pagename|runs| 09:30:12 INFO - PROCESS | 29489 | |0;dojo.html;3054.648715397729;3090.3664748726515;3097.798643088198;3130.1705048162225;3145.245141166121;5278.996990571789;5320.494553017462;5340.839774806752;5342.465753424655;5363.678179309244;5307.708076055816;5377.892442151155;5387.78448862045;5395.81114660987;5430.56555475561;4830.9275360869615;4857.417109946811;4877.219644856824;4899.363082799231;4942.258661200813;4914.410270767513;4973.229149481836;4978.850634480971;4982.551570358667;5030.547250747432;4787.497312782142;4905.3623029006;4918.9508104919205;4973.552380285773;4983.700977941317;4810.460652591172;4810.494898035691;4865.464798872121;4868.975655121737;4890.046440944017;4803.18345881201;4822.324874517571;4822.7829747661435;4842.2736589511505;4878.512148785093;5318.308619879391;5319.308489896327;5325.360956685184;5334.3598768147685;5341.412444631087;5566.499015088621;5578.0517312057045;5582.469665381782;5672.234248376458;5713.485786279213;5699.059655156894;5709.800156994485;5718.656880587178;5720.628159169641;5721.284839395075;5323.707196104251;5425.565954723613;5442.428545002796;5449.700266485382;5451.618386712932;5812.854678633068;5841.094630332305;5854.18041474194;5880.764769409261;5882.3529411764675;5663.943360566342;5671.064274394761;5671.177679236487;5672.8865422691315;5674.035413979634;5572.108462645957;5608.523275521545;5637.548996080303;5641.379448260688;5717.428257174332;5602.915956260659;5629.887402252014;5632.014397480424;5678.148277758287;5701.116326969326;5627.943720562824;5650.604457688005;5657.60396772222;5661.2923384576925;5664.150377443334;4348.5216626171095;4355.412019377407;4368.148211098805;4377.846775362847;4413.404190434315;3957.7229593928723;3972.940405893914;3987.182627561343;4017.2367250222364;4020.8592699255387;5365.222042803772;5390.407055223922;5412.91880621791;5491.478309560585;5493.368262649847;5758.7696492140685;5764.423557644202;5776.42235776419;5814.476697097281;5815.2149459822385;5239.1355426354985;5295.4969277918535;5368;5410.621256512007;5412.107002344569;4843.564079232886;4884.780184891689;4967.776450059756;4976.353074100416;5020.397552293748;5836.620619659709;5868.413158684098;5881.206037185011;5905.232319798399;5955.7915472957375;5681.488666020078;5705.686187259741;5712.25740653729;5712.828615141552;5727.971360143172;5795.913061304168;5821.155932389865;5872.442117998783;5879.5002424794375;5887.028640274478;5549.889002220014;5627.380988091389;5630.85922851932;5632.324121105494;5634.23937768396;5733.31200255972;5735.56983226268;5736.68448235351;5741.971290143522;5742.310922689304;5599.916001260066;5604.8318550443555;5609.691466969356;5610.635308704921;5611.382747897646;5559.916601251065;5595.272614560081;5600.775968961196;5608.1027035675725;5610.5231055360755;5529.281193444987;5536.557075433875;5553.805616803393;5554.666719996813;5555.416681248411;5514.228008078792;5622.24099746529;5634.4365563443325;5637.830865074055;5648.265725455664;5533.059379905506;5547.861303467445;5558.805441809679;5559.610827242054;5559.6664200148125 09:30:12 INFO - PROCESS | 29489 | |1;ext.html;551.5256879083988;597.8565144365353;597.9431953964372;609.4362714489097;615.1388056720591;39883.60116398839;41434.58565414345;41489.79255103728;44480.55519444805;44497.7775111124;42660.44037357761;42792.14415711692;43096.78451607745;43108.35337469933;43157.35263971035;7810;8413.453125546836;8561.914380856175;8567.657293708275;8569.485830850137;7766;8004.799880002988;8013.358931285512;9866;10140.746481337952;9843.753906152331;9880.061394167542;9884.654037108703;9886.159676427513;9907.702768916944;10179.338343007681;10218.386896786216;10219.284650074498;10224.335418197794;10228.028337307944;10005.649802256887;10064.69805905824;10080.647177348757;10084.042016008467;10089.091981721607;39349.40975885364;39545.20909581821;39547.8022609888;39665.60334396648;39710.20579588392;3628.455731640275;3701.611330810253;3901.941470877911;4011.378525275355;4068.2066996935314;29903.364978428377;30299.242518936982;30433.847830760704;30926;32167.195820104684;4071.816768245436;4114.0948991221885;4135.60640435601;4147.875563733093;4150.65181086269;325232;326753;327020;327244.36377818196;330374;54284.728576356865;54555;54608;54680;54694.45305546893;57244.42755572391;57420.42579574235;57431.712841435525;57503.67459579443;57774.133387999216;15557.533274001798;16218.75671864923;16306.836931630769;16334.591635208977;16335.836641633668;11897.286162830258;12316.876831231573;12328.568500102632;12330.445129969174;12332.876671233353;13990.370433330525;14304.141751494944;15198.924005380124;15475;15622.9218853905;6086.421789929949;6143.600665956787;6154.446099850946;6163;6236.220472440945;924.1451657217048;987.4470296633908;1011.2920955331298;1059.6344261229863;1109.8945600167972;30381.240468988453;30837.845810770803;30892.382152356826;30926.845365773028;31107.222319442197;1097.2100087936674;1142.2917790969486;1158.7103224193952;1174.1155445510005;1205.0600531585378;16110.516684499484;16234.188290585424;16325.755113673304;16402.671946561;16679;457797;460415;461758;462170;463942;54147.458525414244;54300;54424.45575544273;54445.45554544483;54541;1285.2802430638872;1339.6249050265942;1348.4875747216192;1356.2947267420886;1375.5048182654236;28795.5680664786;29352.412951741273;29994;30552.388952220386;30673.233169170948;48387.27419088646;51135.74432127964;52491.737541312046;52515.212271816716;57270;470863;472794;474071;474116;474642;27054.458910822068;27202.31994200161;27517.174484765488;27586.448271034067;27593.58609620817;37201;37349;46427.30359044685;47950;48236.03527929492;38213;38394.42408363821;38686.613133868304;39453.605463944994;40138.39792403175;37104.175885368284;38293.234135317696;39019;39044.804775975936;39382.81851544458;38121.80939095287;38204;38674.80662596669;38745.93167717349;39106.4134037984;33229.50155747714;35584;36320.55191721263;36412.81793591121;36437;26576.601350979363;27286.590701139103;27298.181054568395;27374.863125684245;27634.309142271602 09:30:12 INFO - PROCESS | 29489 | |2;jquery.html;361.27744510978044;393.3804258293189;399.4268225096986;404.64391335624646;414.26172996493176;13251.867481325184;15205.695886082272;15898.523044308675;18813.341533046347;19307.227710891548;17885.552861178443;18130.818691813078;18316.81683183171;18498.53753656159;18514.907425462887;5278.683279003253;5482.205080263359;5484.369297530775;5485.451454854503;5488.670679759207;5123.897522049557;5370.8925821483745;5405.756740946647;5408;5408.675479471224;5385.596080293974;5388.568914486851;5388.811391601294;5394.703291318976;5397.568194544447;5181.35233095863;5255.605829562779;5295.523402893758;5306.575473962073;5310.256564081013;4759.071980963713;4763.737994410306;4765.09463201994;4768.284757286435;4768.380110585636;20895.164193432243;21650.133994640197;21761.564768704695;21813.345599632037;21829.454263643376;2385.1651921827397;2448.0207916833233;2575.626534152556;2831.065748303055;2896.348321627638;13456.93271533646;13465.528706495226;13528.729425411437;13945.860541394659;14275.786558142467;1842.8065053169491;1853.8426859416152;1899.6390685769659;1904.7047707605343;1911.0922311901875;76743;81000;81008;81683;81835;9815.263855210887;10120.493975301206;10503.94748026255;10610;10780.46097695112;13452.663683407993;13462.798058028942;13466.797998030037;13489.2580908051;13502.257375844423;7179.89230161548;7424.703011879572;7505.662245198979;7731.188225236382;7740.148583655793;6940.618265995317;6965.233824279325;7032.5428847125795;7176.246494118147;7261.164966028974;6355.650439225795;6359.395857393541;6404.455621272151;6415.807525774234;6454.774082907075;1812.6284111757059;1885.6888613378912;1887.7262796894374;1928.5757133430623;1955.1885967323685;883.8055627761883;979.5591983607405;1024.8104100741239;1034.7620047388991;1036.3108532825738;18153.717294192906;18568.81431185698;19228;19276;19471.610567788564;991.3902949685892;1011.5599714124379;1013.4729940430934;1027.4246422003703;1049.5486940615608;10308.793824123475;10801.297915635458;10844.945775271073;10903.29128606638;10955.287906286067;422393;422587;424699;429707;432054;76448;79249;80366;80569;81181.59409202916;993.2252842782641;1000.8748906386702;1049.464772965793;1051.0960573906434;1053.3206082077018;15963.600909977344;16214.189290535662;16541.338346466488;16600.50198494047;16796.58008549796;39347.409788853765;39503;41020.79489602652;41690.16619667529;42582.5741742591;50481;51623.48376516337;52126.73936630293;58211.417885822295;60342.39657603368;7644.197359277196;7862.52824830512;7890.802729931568;7917;8137.308328792119;10192.490375481344;12047.578334758242;12190.878091218974;12412.875871241173;12438.440270187682;10751.516181771683;10756.892431075903;10768.138548916224;11177.55289788432;11359.772804543698;10818.1886358525;10887.673369798918;10960.068394186583;11018.338899666045;11182.888171118184;10928.562857485611;11118.166137539554;11570.710732231424;11689.181757276909;12019.978301844092;7921.722739704313;8113.6754529818145;8270.2556769891;8311.625976830936;8399.790005249917;9553.23574114083;11187.832182517432;11250.493727782434;11520.769584608093;11536.134789890624 09:30:12 INFO - PROCESS | 29489 | |3;mootools.html;1236.1100007994241;1407.957761267162;1433.2833583208396;1527.2821773766325;1719.4755599542134;7263.60050197239;7846.411519136066;8056.315213206884;8066;8316.542590157538;8147.063087744911;8190.344772418207;8192.836143277145;8205.056418511873;8206.958965205165;1907.9904600477048;2158.093600687715;2187.3219302016428;2188.3325585696357;2190.5071358944188;1998;2203.173809821317;2258.1983395894467;2261.061659411342;2291.541691661666;2209.9558008839813;2212.214663794354;2227.654713519407;2229.7435794883554;2239.2050821958214;2160.8379371547117;2164.945876353088;2168.3494951514563;2169.0889826273005;2176.706144670465;2135.177956486764;2161.384005558424;2194.8353873459555;2200.3618950504333;2204.206485665159;8192.836143277102;8304.418690691655;8307.750767476986;8313;8325.375596830261;4865.951340486586;4984.87537811554;4991.825286115005;4992.076465853793;4996.975015124937;3167.509036099408;3297.686719761619;3342.331533693271;3381.5942086949476;3415.077928959192;1006.1726548398949;1103.757173421846;1104.127739086129;2065.1842522203647;2101.0860275780083;11349.602763903224;11461.770764584662;11529.884701152965;11590.304581725124;11590.652280431601;4083.4487344208746;4246.280256496124;4303.07483890965;4365.345198220292;4366.8908277292685;8972.19250267479;9001.324900632479;9004.54977251148;9011.729648110568;9015.27877769777;4987.825426110068;4987.850364489072;4988.326575912205;4988.6507944444265;4992.176290912032;3722.4147819365244;4425.800838962255;4536.705114167635;4586.954130458719;4590.104929538775;3500.824958752052;3510.104923244556;3570.321638888603;3587.5694916610173;3609.0796846803896;1064.6273804168634;1074.215822449616;1077.5312738958578;1078.6763970808727;1085.6254592165849;829.742779738283;944.7449188719007;946.711253067821;952.9952350238204;973.6543527047796;5493.395726470085;5504.669719816824;5583.860403489864;5630.746616402272;5652.858678532987;411.44249541870334;426.7204980737622;427.1584977594135;431.22164493089645;437.72642098688317;3760.2291530236234;3815.904602384963;3833.5783063862955;3852.3643598805884;3905.33609286422;9716.417014979124;9940.304178707565;9980.650677226407;10065.64770233053;10070.647527336654;9812.925509613222;9990;10008.249381296431;10009.499525023719;10014.79970400588;7686.269804368576;7755.379569634416;7792.922070779333;7803.219678032151;7804.492707973964;5441.483059109379;5494.313210848644;5506.146547285177;5600.467955544217;5610.18652295423;9035.909640903506;9179.127982841619;9255.074492550692;9277.304202184729;9440.716778496657;9418.434893906388;9492.715218543455;9521.476318802532;9542.570584323861;9723.513824308615;6785.117934668461;6936.7225310987;7033.648317584202;7418.109826820816;7502.587357695379;7635.160132385322;7880.645370958206;7920.84158316842;8061.758147255592;8069.59652017385;7040.612766297902;7208.06295181623;7240.927590724236;7417.109946806418;7464.552126872188;6994.6852391643515;7105.715771369294;7165.104361954755;7223.783286501413;7230.42156627458;6466.3510804746375;6606.900896486461;6608.70260838273;6611.371919667817;6652.73389064432;4870.78081486341;4933.6299777516115;4957.231629097496;5066.240063990431;5120.897582048264;4961.67749096322;5002.074616196015;5124.795673016763;5224.738763061908;5226.869328266824 09:30:12 INFO - PROCESS | 29489 | |4;prototype.html;298.8670041831385;314.7434840604905;317.5522513256309;325.13027651033485;328.9819059951702;5081.64428490005;5167.509086636773;5285.233641122039;5322.520973112419;5355.893581722601;5106.183010718286;5196.142636464979;5214.165733482643;5242.344706911636;5263.842084737455;4115.444415003966;4121.835126594932;4133.627973482386;4135.338345864662;4135.937960930596;4021.517417909855;4081.979590102045;4115.464989551354;4143.523494798107;4151.730137541065;4047.7166598338126;4078.8572399966006;4080.489938757655;4093.485410398142;4095.1809638072355;4060.8984775380554;4067.23428068913;4073.6741060715367;4080.7347522410946;4081.1225586498895;3607.5129857469433;3613.7109031277437;3621.167131559756;3625.1480901988275;3674.6876515496215;4962.60299176065;5079.31429257053;5092.872678183038;5132.40977287613;5161.896762064775;4660.673752837302;4669.813207471697;4680.227762419197;4685.461171965234;4694.460137084245;5438.864028399282;5439.83680489582;5476.58925580579;5492.176173573956;5504.862378440531;3651.981740091309;3841.9807900960595;3873.4189871519216;3888.241792850395;4009.3361255079108;5650.14667592867;5650.943490565124;5666.858328541778;5690.487856092972;5695.800646977336;3945.546262179829;3951.6641085508054;3957.5357117866574;3969.8809035728973;4026.9597304026584;4002.6197511236387;4047.0489434982755;4059.208454351432;4096.5903409658795;4118.320477121301;4664.673472856867;4668.346431499593;4680.251159814414;4691.014886873726;4692.202325604655;5566.912407847058;5576.442355764391;5580.888382232332;5608.887822243614;5619.656304498817;1296.3777386854363;1319.8350206224222;1339.2098661789591;1349.379285528648;1352.8838708065884;919.3380765848578;930.5207817973613;942.3686130292721;951.9143277105094;952.1763674421576;5119.180931051014;5137.229415587692;5140.383154021467;5141.349641754322;5149.716765577854;5363.517283444508;5383.596230282745;5411.107167317348;5413.648112872729;5476.069068258405;4950.683042361627;5161.716105614228;5164.2770012198325;5178.766955486937;5215.765290561933;2918.270432391902;2927.516959701625;2932.633420822397;2942.4262268857797;2942.514485109976;5537.723113844372;5677.9211949729415;5701.458361455655;5707.600467967202;5710.71446427677;5204.323437953042;5457.454254574511;5483.917741233885;5498.560115190775;5516.096946553433;5609.691466969274;5611.915821262684;5620.522255608319;5635.8591035224445;5639.3645842705155;5186.740662966912;5321.3880403754065;5349.1976203569775;5435.592330575144;5448.945510544844;5368.382635996911;5379.67721936685;5382.542483888913;5386.569074473954;5386.919196212139;5581.972090139523;5595.132754423071;5622.762992141886;5625.437456254342;5627.831165065055;3752.7373083883867;3855.9807200963814;3861.4400911868183;3863.7442831080575;3866.2654095721723;5472.589555783253;5634.52106570946;5658.5190258826815;5677.971610141923;5689.772409103755;5436.972815135899;5438.945610543844;5486.079872044753;5531.557475401878;5539.390667026543;5517.420670829505;5525.254090697865;5551.47481349338;5593.412691667317;5604.915926261027;5335.866603334793;5466.835994920159;5498.725063746877;5539.701024074964;5545.306836645419;5572.4706152915405;5573.331200255995;5576.191452239484;5599.216109744558;5609;5451.264079349237;5458.426865179099;5461.15352120422;5501.129615930603;5522.199281104297 09:30:12 INFO - PROCESS | 29489 | |5;yui.html;756.2966441209679;778.9182135875733;809.8889620451182;817.4931542443682;835.4152093534525;15049.548513544598;15693.058416495018;15706;15707.84292157078;15722.641603759414;14691.33888974996;14822;14848.034877732967;14852.554423367315;14874.553763387088;673.0543586261286;684.0389253099403;770.1874522378888;783.8353945671416;785.9764207073782;783.0994356490025;797.7287722174459;797.7566842113154;798.6286376834771;799.4443861516248;842.8356470488258;849.9490030598184;850.7958090058372;852.2244757270885;852.3224036890687;713.8072720365524;728.726727477196;826.8511667899776;846.8179341441589;848.2154007543029;614.9569530132892;618.2179542878263;618.3847072163227;618.5577312221739;619;15020.624484387869;15051.473198438112;15123.773143402857;15160.469383571522;15180.240987950558;13625.795613065811;13753.931230343884;13785.448582056706;13818.239996800172;13991.510297139552;12922.547710830077;13015.544455943997;13038.478460861554;13390.732185356239;13559.118657287245;4257.939562486045;4377.518472968003;4386.429764130643;4414.933775993362;4433.778311084465;74325.62837185833;77274;78121.60939195324;79202.20797792006;79368.60315698442;11036.668899933014;11300.773984520429;11434.428278586038;11721.35532545701;11853;22150.778492215195;22181.778182218295;22206.66689999651;22210.11159553632;22341.664875026887;8118.918810811934;8168.183181681784;8192.374781958635;8250.47366237248;8438.36712246584;4512.323151527297;4595.287730401793;4602.401687780567;4661.230896902041;4694.929576056362;3320.3193345364143;3427.2288735034917;3428.160100775326;3432.4508078707786;3435.055359776082;555.1672491263105;562.1483452569361;577.4946921443736;583.8190161050088;592.5644651181378;560.5823661372303;611.1871211288975;618.2797041446754;682.5461068389565;694.4964900447236;9023.187913087853;9370.156685898302;9388.776361253393;9647.807043859084;9727.416355018722;645.7998020613625;652.774792696519;670.902719105727;680.5848432456198;700.2122612061431;4351.782410879507;4627.884302892455;4740.5733483986605;4752.643551733634;4804.759762011955;87622;87670;87891.56054219816;88771.55614222017;90872;73760.82946313906;75198;75367.62316188494;75649;76080.61959690276;65484;67570;70072.29927700658;71024.2897571028;71382.28617713756;9018.954905225432;9217.539123043955;9246.583903724215;9368.297377696836;9414.62341506332;39124.21751564896;41262.79368603258;43600.34599481074;44083.33874991942;45416.54583454123;57654.4234557649;58778;61824.38175618366;62150.68924655348;63218.36781632309;11900.76198476043;11948.103892208293;12016.819747703617;12087.576934807239;12200.328981906081;12582.043343653251;12790.929181515814;12832.422540985492;12873.485060597472;13192.406341714453;12005.39973001329;12378.938105309417;12388.56640017595;12521.87478125207;12698.111132220656;11831.17181797266;12600.684982875502;12999.220046797222;13121.868781312065;13138.540151095049;11603.477843497232;11676.532938682358;11875.346855923008;12190.634280971584;12741.17182383142;9780.853287200556;10055.195584353089;10374.481275936323;10485.623827624808;10576.20678449104;8327.625256863335;8983.955080224558;9065.311106127223;9443.905560944302;9962.452065136487 09:30:12 INFO - PROCESS | 29489 | __end_tp_report 09:30:12 INFO - PROCESS | 29489 | __start_cc_report 09:30:12 INFO - PROCESS | 29489 | _x_x_mozilla_cycle_collect,2619 09:30:12 INFO - PROCESS | 29489 | __end_cc_report 09:30:12 INFO - PROCESS | 29489 | __startTimestamp1480267812920__endTimestamp 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | ------- Summary: start ------- 09:30:12 INFO - PROCESS | 29489 | Number of tests: 6 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#0] dojo.html Cycles:165 Average:5314.79 Median:5554.67 stddev:586.45 (10.6%) stddev-sans-first:560.80 09:30:12 INFO - PROCESS | 29489 | Values: 3054.6 3090.4 3097.8 3130.2 3145.2 5279.0 5320.5 5340.8 5342.5 5363.7 5307.7 5377.9 5387.8 5395.8 5430.6 4830.9 4857.4 4877.2 4899.4 4942.3 4914.4 4973.2 4978.9 4982.6 5030.5 4787.5 4905.4 4919.0 4973.6 4983.7 4810.5 4810.5 4865.5 4869.0 4890.0 4803.2 4822.3 4822.8 4842.3 4878.5 5318.3 5319.3 5325.4 5334.4 5341.4 5566.5 5578.1 5582.5 5672.2 5713.5 5699.1 5709.8 5718.7 5720.6 5721.3 5323.7 5425.6 5442.4 5449.7 5451.6 5812.9 5841.1 5854.2 5880.8 5882.4 5663.9 5671.1 5671.2 5672.9 5674.0 5572.1 5608.5 5637.5 5641.4 5717.4 5602.9 5629.9 5632.0 5678.1 5701.1 5627.9 5650.6 5657.6 5661.3 5664.2 4348.5 4355.4 4368.1 4377.8 4413.4 3957.7 3972.9 3987.2 4017.2 4020.9 5365.2 5390.4 5412.9 5491.5 5493.4 5758.8 5764.4 5776.4 5814.5 5815.2 5239.1 5295.5 5368.0 5410.6 5412.1 4843.6 4884.8 4967.8 4976.4 5020.4 5836.6 5868.4 5881.2 5905.2 5955.8 5681.5 5705.7 5712.3 5712.8 5728.0 5795.9 5821.2 5872.4 5879.5 5887.0 5549.9 5627.4 5630.9 5632.3 5634.2 5733.3 5735.6 5736.7 5742.0 5742.3 5599.9 5604.8 5609.7 5610.6 5611.4 5559.9 5595.3 5600.8 5608.1 5610.5 5529.3 5536.6 5553.8 5554.7 5555.4 5514.2 5622.2 5634.4 5637.8 5648.3 5533.1 5547.9 5558.8 5559.6 5559.7 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#1] ext.html Cycles:180 Average:57553.54 Median:38716.27 stddev:112763.00 (291.3%) stddev-sans-first:112998.12 09:30:12 INFO - PROCESS | 29489 | Values: 551.5 597.9 597.9 609.4 615.1 39883.6 41434.6 41489.8 44480.6 44497.8 42660.4 42792.1 43096.8 43108.4 43157.4 7810.0 8413.5 8561.9 8567.7 8569.5 7766.0 8004.8 8013.4 9866.0 10140.7 9843.8 9880.1 9884.7 9886.2 9907.7 10179.3 10218.4 10219.3 10224.3 10228.0 10005.6 10064.7 10080.6 10084.0 10089.1 39349.4 39545.2 39547.8 39665.6 39710.2 3628.5 3701.6 3901.9 4011.4 4068.2 29903.4 30299.2 30433.8 30926.0 32167.2 4071.8 4114.1 4135.6 4147.9 4150.7 325232.0 326753.0 327020.0 327244.4 330374.0 54284.7 54555.0 54608.0 54680.0 54694.5 57244.4 57420.4 57431.7 57503.7 57774.1 15557.5 16218.8 16306.8 16334.6 16335.8 11897.3 12316.9 12328.6 12330.4 12332.9 13990.4 14304.1 15198.9 15475.0 15622.9 6086.4 6143.6 6154.4 6163.0 6236.2 924.1 987.4 1011.3 1059.6 1109.9 30381.2 30837.8 30892.4 30926.8 31107.2 1097.2 1142.3 1158.7 1174.1 1205.1 16110.5 16234.2 16325.8 16402.7 16679.0 457797.0 460415.0 461758.0 462170.0 463942.0 54147.5 54300.0 54424.5 54445.5 54541.0 1285.3 1339.6 1348.5 1356.3 1375.5 28795.6 29352.4 29994.0 30552.4 30673.2 48387.3 51135.7 52491.7 52515.2 57270.0 470863.0 472794.0 474071.0 474116.0 474642.0 27054.5 27202.3 27517.2 27586.4 27593.6 37201.0 37349.0 46427.3 47950.0 48236.0 38213.0 38394.4 38686.6 39453.6 40138.4 37104.2 38293.2 39019.0 39044.8 39382.8 38121.8 38204.0 38674.8 38745.9 39106.4 33229.5 35584.0 36320.6 36412.8 36437.0 26576.6 27286.6 27298.2 27374.9 27634.3 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#2] jquery.html Cycles:180 Average:26420.79 Median:2511.82 stddev:70402.69 (2802.9%) stddev-sans-first:70573.00 09:30:12 INFO - PROCESS | 29489 | Values: 361.3 393.4 399.4 404.6 414.3 13251.9 15205.7 15898.5 18813.3 19307.2 17885.6 18130.8 18316.8 18498.5 18514.9 5278.7 5482.2 5484.4 5485.5 5488.7 5123.9 5370.9 5405.8 5408.0 5408.7 5385.6 5388.6 5388.8 5394.7 5397.6 5181.4 5255.6 5295.5 5306.6 5310.3 4759.1 4763.7 4765.1 4768.3 4768.4 20895.2 21650.1 21761.6 21813.3 21829.5 2385.2 2448.0 2575.6 2831.1 2896.3 13456.9 13465.5 13528.7 13945.9 14275.8 1842.8 1853.8 1899.6 1904.7 1911.1 76743.0 81000.0 81008.0 81683.0 81835.0 9815.3 10120.5 10503.9 10610.0 10780.5 13452.7 13462.8 13466.8 13489.3 13502.3 7179.9 7424.7 7505.7 7731.2 7740.1 6940.6 6965.2 7032.5 7176.2 7261.2 6355.7 6359.4 6404.5 6415.8 6454.8 1812.6 1885.7 1887.7 1928.6 1955.2 883.8 979.6 1024.8 1034.8 1036.3 18153.7 18568.8 19228.0 19276.0 19471.6 991.4 1011.6 1013.5 1027.4 1049.5 10308.8 10801.3 10844.9 10903.3 10955.3 422393.0 422587.0 424699.0 429707.0 432054.0 76448.0 79249.0 80366.0 80569.0 81181.6 993.2 1000.9 1049.5 1051.1 1053.3 15963.6 16214.2 16541.3 16600.5 16796.6 39347.4 39503.0 41020.8 41690.2 42582.6 50481.0 51623.5 52126.7 58211.4 60342.4 7644.2 7862.5 7890.8 7917.0 8137.3 10192.5 12047.6 12190.9 12412.9 12438.4 10751.5 10756.9 10768.1 11177.6 11359.8 10818.2 10887.7 10960.1 11018.3 11182.9 10928.6 11118.2 11570.7 11689.2 12020.0 7921.7 8113.7 8270.3 8311.6 8399.8 9553.2 11187.8 11250.5 11520.8 11536.1 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#3] mootools.html Cycles:180 Average:5394.49 Median:4991.95 stddev:3032.05 (60.7%) stddev-sans-first:3024.45 09:30:12 INFO - PROCESS | 29489 | Values: 1236.1 1408.0 1433.3 1527.3 1719.5 7263.6 7846.4 8056.3 8066.0 8316.5 8147.1 8190.3 8192.8 8205.1 8207.0 1908.0 2158.1 2187.3 2188.3 2190.5 1998.0 2203.2 2258.2 2261.1 2291.5 2210.0 2212.2 2227.7 2229.7 2239.2 2160.8 2164.9 2168.3 2169.1 2176.7 2135.2 2161.4 2194.8 2200.4 2204.2 8192.8 8304.4 8307.8 8313.0 8325.4 4866.0 4984.9 4991.8 4992.1 4997.0 3167.5 3297.7 3342.3 3381.6 3415.1 1006.2 1103.8 1104.1 2065.2 2101.1 11349.6 11461.8 11529.9 11590.3 11590.7 4083.4 4246.3 4303.1 4365.3 4366.9 8972.2 9001.3 9004.5 9011.7 9015.3 4987.8 4987.9 4988.3 4988.7 4992.2 3722.4 4425.8 4536.7 4587.0 4590.1 3500.8 3510.1 3570.3 3587.6 3609.1 1064.6 1074.2 1077.5 1078.7 1085.6 829.7 944.7 946.7 953.0 973.7 5493.4 5504.7 5583.9 5630.7 5652.9 411.4 426.7 427.2 431.2 437.7 3760.2 3815.9 3833.6 3852.4 3905.3 9716.4 9940.3 9980.7 10065.6 10070.6 9812.9 9990.0 10008.2 10009.5 10014.8 7686.3 7755.4 7792.9 7803.2 7804.5 5441.5 5494.3 5506.1 5600.5 5610.2 9035.9 9179.1 9255.1 9277.3 9440.7 9418.4 9492.7 9521.5 9542.6 9723.5 6785.1 6936.7 7033.6 7418.1 7502.6 7635.2 7880.6 7920.8 8061.8 8069.6 7040.6 7208.1 7240.9 7417.1 7464.6 6994.7 7105.7 7165.1 7223.8 7230.4 6466.4 6606.9 6608.7 6611.4 6652.7 4870.8 4933.6 4957.2 5066.2 5120.9 4961.7 5002.1 5124.8 5224.7 5226.9 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#4] prototype.html Cycles:180 Average:4578.89 Median:5214.97 stddev:1342.82 (25.7%) stddev-sans-first:1307.59 09:30:12 INFO - PROCESS | 29489 | Values: 298.9 314.7 317.6 325.1 329.0 5081.6 5167.5 5285.2 5322.5 5355.9 5106.2 5196.1 5214.2 5242.3 5263.8 4115.4 4121.8 4133.6 4135.3 4135.9 4021.5 4082.0 4115.5 4143.5 4151.7 4047.7 4078.9 4080.5 4093.5 4095.2 4060.9 4067.2 4073.7 4080.7 4081.1 3607.5 3613.7 3621.2 3625.1 3674.7 4962.6 5079.3 5092.9 5132.4 5161.9 4660.7 4669.8 4680.2 4685.5 4694.5 5438.9 5439.8 5476.6 5492.2 5504.9 3652.0 3842.0 3873.4 3888.2 4009.3 5650.1 5650.9 5666.9 5690.5 5695.8 3945.5 3951.7 3957.5 3969.9 4027.0 4002.6 4047.0 4059.2 4096.6 4118.3 4664.7 4668.3 4680.3 4691.0 4692.2 5566.9 5576.4 5580.9 5608.9 5619.7 1296.4 1319.8 1339.2 1349.4 1352.9 919.3 930.5 942.4 951.9 952.2 5119.2 5137.2 5140.4 5141.3 5149.7 5363.5 5383.6 5411.1 5413.6 5476.1 4950.7 5161.7 5164.3 5178.8 5215.8 2918.3 2927.5 2932.6 2942.4 2942.5 5537.7 5677.9 5701.5 5707.6 5710.7 5204.3 5457.5 5483.9 5498.6 5516.1 5609.7 5611.9 5620.5 5635.9 5639.4 5186.7 5321.4 5349.2 5435.6 5448.9 5368.4 5379.7 5382.5 5386.6 5386.9 5582.0 5595.1 5622.8 5625.4 5627.8 3752.7 3856.0 3861.4 3863.7 3866.3 5472.6 5634.5 5658.5 5678.0 5689.8 5437.0 5438.9 5486.1 5531.6 5539.4 5517.4 5525.3 5551.5 5593.4 5604.9 5335.9 5466.8 5498.7 5539.7 5545.3 5572.5 5573.3 5576.2 5599.2 5609.0 5451.3 5458.4 5461.2 5501.1 5522.2 09:30:12 INFO - PROCESS | 29489 | 09:30:12 INFO - PROCESS | 29489 | [#5] yui.html Cycles:180 Average:18111.82 Median:561.37 stddev:24213.68 (4313.4%) stddev-sans-first:24246.53 09:30:12 INFO - PROCESS | 29489 | Values: 756.3 778.9 809.9 817.5 835.4 15049.5 15693.1 15706.0 15707.8 15722.6 14691.3 14822.0 14848.0 14852.6 14874.6 673.1 684.0 770.2 783.8 786.0 783.1 797.7 797.8 798.6 799.4 842.8 849.9 850.8 852.2 852.3 713.8 728.7 826.9 846.8 848.2 615.0 618.2 618.4 618.6 619.0 15020.6 15051.5 15123.8 15160.5 15180.2 13625.8 13753.9 13785.4 13818.2 13991.5 12922.5 13015.5 13038.5 13390.7 13559.1 4257.9 4377.5 4386.4 4414.9 4433.8 74325.6 77274.0 78121.6 79202.2 79368.6 11036.7 11300.8 11434.4 11721.4 11853.0 22150.8 22181.8 22206.7 22210.1 22341.7 8118.9 8168.2 8192.4 8250.5 8438.4 4512.3 4595.3 4602.4 4661.2 4694.9 3320.3 3427.2 3428.2 3432.5 3435.1 555.2 562.1 577.5 583.8 592.6 560.6 611.2 618.3 682.5 694.5 9023.2 9370.2 9388.8 9647.8 9727.4 645.8 652.8 670.9 680.6 700.2 4351.8 4627.9 4740.6 4752.6 4804.8 87622.0 87670.0 87891.6 88771.6 90872.0 73760.8 75198.0 75367.6 75649.0 76080.6 65484.0 67570.0 70072.3 71024.3 71382.3 9019.0 9217.5 9246.6 9368.3 9414.6 39124.2 41262.8 43600.3 44083.3 45416.5 57654.4 58778.0 61824.4 62150.7 63218.4 11900.8 11948.1 12016.8 12087.6 12200.3 12582.0 12790.9 12832.4 12873.5 13192.4 12005.4 12378.9 12388.6 12521.9 12698.1 11831.2 12600.7 12999.2 13121.9 13138.5 11603.5 11676.5 11875.3 12190.6 12741.2 9780.9 10055.2 10374.5 10485.6 10576.2 8327.6 8984.0 9065.3 9443.9 9962.5 09:30:12 INFO - PROCESS | 29489 | -------- Summary: end -------- 09:30:12 INFO - PROCESS | 29489 | 09:30:13 INFO - TEST-INFO | 29489: exit 0 09:30:13 INFO - TEST-OK | dromaeo_css | took 1118462ms 09:30:13 INFO - TEST-START | kraken 09:30:13 INFO - operating with platform_type : linux_ 09:30:13 INFO - Initialising browser for kraken test... 09:30:13 INFO - TEST-INFO | started process 29796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGRPi06/profile http://localhost:38566/getInfo.html) 09:30:18 INFO - PROCESS | 29796 | __metrics Screen width/height:1600/1200 09:30:18 INFO - PROCESS | 29796 | colorDepth:24 09:30:18 INFO - PROCESS | 29796 | Browser inner width/height: 1024/697 09:30:18 INFO - PROCESS | 29796 | __metrics 09:30:18 INFO - PROCESS | 29796 | 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" 09:30:18 INFO - PROCESS | 29796 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:20 INFO - TEST-INFO | 29796: exit 0 09:30:20 INFO - Browser initialized. 09:30:20 INFO - Running cycle 1/1 for kraken test... 09:30:20 INFO - TEST-INFO | started process 29882 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGRPi06/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:32 INFO - PROCESS | 29882 | RSS: Main: 173408256 09:30:32 INFO - PROCESS | 29882 | 09:30:34 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38566/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:35 INFO - PROCESS | 29882 | RSS: Main: 234647552 09:30:35 INFO - PROCESS | 29882 | 09:30:38 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38566/tests/kraken/driver.html?testName=audio-dft) 09:30:38 INFO - PROCESS | 29882 | RSS: Main: 216731648 09:30:38 INFO - PROCESS | 29882 | 09:30:41 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38566/tests/kraken/driver.html?testName=audio-fft) 09:30:42 INFO - PROCESS | 29882 | RSS: Main: 298799104 09:30:42 INFO - PROCESS | 29882 | 09:30:44 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38566/tests/kraken/driver.html?testName=audio-oscillator) 09:30:45 INFO - PROCESS | 29882 | RSS: Main: 208793600 09:30:45 INFO - PROCESS | 29882 | 09:30:47 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:47 INFO - PROCESS | 29882 | RSS: Main: 214560768 09:30:47 INFO - PROCESS | 29882 | 09:30:50 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38566/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:51 INFO - PROCESS | 29882 | RSS: Main: 253374464 09:30:51 INFO - PROCESS | 29882 | 09:30:54 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38566/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:55 INFO - PROCESS | 29882 | RSS: Main: 253112320 09:30:55 INFO - PROCESS | 29882 | 09:30:58 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38566/tests/kraken/driver.html?testName=json-parse-financial) 09:30:58 INFO - PROCESS | 29882 | RSS: Main: 251949056 09:30:58 INFO - PROCESS | 29882 | 09:31:00 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:01 INFO - PROCESS | 29882 | RSS: Main: 214392832 09:31:01 INFO - PROCESS | 29882 | 09:31:02 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:03 INFO - PROCESS | 29882 | RSS: Main: 221249536 09:31:03 INFO - PROCESS | 29882 | 09:31:05 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:06 INFO - PROCESS | 29882 | RSS: Main: 230207488 09:31:06 INFO - PROCESS | 29882 | 09:31:08 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:09 INFO - PROCESS | 29882 | RSS: Main: 238403584 09:31:09 INFO - PROCESS | 29882 | 09:31:12 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:12 INFO - PROCESS | 29882 | RSS: Main: 235986944 09:31:12 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | Cycle 1(1): loaded http://localhost:38566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38566/tests/kraken/driver.html?testName=ai-astar) 09:31:14 INFO - PROCESS | 29882 | RSS: Main: 287334400 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | __start_tp_report 09:31:14 INFO - PROCESS | 29882 | _x_x_mozilla_page_load 09:31:14 INFO - PROCESS | 29882 | _x_x_mozilla_page_load_details 09:31:14 INFO - PROCESS | 29882 | |i|pagename|runs| 09:31:14 INFO - PROCESS | 29882 | |0;ai-astar;110;115;112;112;113;112;111;112;112;111 09:31:14 INFO - PROCESS | 29882 | |1;audio-beat-detection;146;125;126;167;125;127;124;126;155;129 09:31:14 INFO - PROCESS | 29882 | |2;audio-dft;161;161;161;161;161;162;161;161;161;161 09:31:14 INFO - PROCESS | 29882 | |3;audio-fft;75;76;76;109;78;77;76;76;93;76 09:31:14 INFO - PROCESS | 29882 | |4;audio-oscillator;83;84;108;83;83;83;97;84;83;111 09:31:14 INFO - PROCESS | 29882 | |5;imaging-gaussian-blur;118;118;116;117;117;117;117;117;116;116 09:31:14 INFO - PROCESS | 29882 | |6;imaging-darkroom;146;147;145;146;146;147;145;146;145;146 09:31:14 INFO - PROCESS | 29882 | |7;imaging-desaturate;97;96;96;96;95;96;96;96;97;96 09:31:14 INFO - PROCESS | 29882 | |8;json-parse-financial;52;52;55;50;54;52;54;52;50;52 09:31:14 INFO - PROCESS | 29882 | |9;json-stringify-tinderbox;61;62;62;67;63;62;62;62;62;62 09:31:14 INFO - PROCESS | 29882 | |10;stanford-crypto-aes;82;81;80;80;80;84;95;80;80;81 09:31:14 INFO - PROCESS | 29882 | |11;stanford-crypto-ccm;143;136;133;137;141;141;131;129;169;149 09:31:14 INFO - PROCESS | 29882 | |12;stanford-crypto-pbkdf2;169;166;164;166;168;166;171;165;170;167 09:31:14 INFO - PROCESS | 29882 | |13;stanford-crypto-sha256-iterative;69;66;66;66;67;70;66;64;71;64 09:31:14 INFO - PROCESS | 29882 | __end_tp_report 09:31:14 INFO - PROCESS | 29882 | __start_cc_report 09:31:14 INFO - PROCESS | 29882 | _x_x_mozilla_cycle_collect,1664 09:31:14 INFO - PROCESS | 29882 | __end_cc_report 09:31:14 INFO - PROCESS | 29882 | __startTimestamp1480267874820__endTimestamp 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | ------- Summary: start ------- 09:31:14 INFO - PROCESS | 29882 | Number of tests: 14 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:1.33 (1.2%) stddev-sans-first:1.20 09:31:14 INFO - PROCESS | 29882 | Values: 110.0 115.0 112.0 112.0 113.0 112.0 111.0 112.0 112.0 111.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:128.00 stddev:15.38 (12.0%) stddev-sans-first:15.79 09:31:14 INFO - PROCESS | 29882 | Values: 146.0 125.0 126.0 167.0 125.0 127.0 124.0 126.0 155.0 129.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 09:31:14 INFO - PROCESS | 29882 | Values: 161.0 161.0 161.0 161.0 161.0 162.0 161.0 161.0 161.0 161.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.12 (14.6%) stddev-sans-first:11.57 09:31:14 INFO - PROCESS | 29882 | Values: 75.0 76.0 76.0 109.0 78.0 77.0 76.0 76.0 93.0 76.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:83.00 stddev:11.21 (13.5%) stddev-sans-first:11.61 09:31:14 INFO - PROCESS | 29882 | Values: 83.0 84.0 108.0 83.0 83.0 83.0 97.0 84.0 83.0 111.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 09:31:14 INFO - PROCESS | 29882 | Values: 118.0 118.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:31:14 INFO - PROCESS | 29882 | Values: 146.0 147.0 145.0 146.0 146.0 147.0 145.0 146.0 145.0 146.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:31:14 INFO - PROCESS | 29882 | Values: 97.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 97.0 96.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:1.64 (3.1%) stddev-sans-first:1.73 09:31:14 INFO - PROCESS | 29882 | Values: 52.0 52.0 55.0 50.0 54.0 52.0 54.0 52.0 50.0 52.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:62.00 stddev:1.65 (2.7%) stddev-sans-first:1.66 09:31:14 INFO - PROCESS | 29882 | Values: 61.0 62.0 62.0 67.0 63.0 62.0 62.0 62.0 62.0 62.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:81.00 stddev:4.64 (5.7%) stddev-sans-first:4.92 09:31:14 INFO - PROCESS | 29882 | Values: 82.0 81.0 80.0 80.0 80.0 84.0 95.0 80.0 80.0 81.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:141.00 stddev:11.55 (8.2%) stddev-sans-first:12.23 09:31:14 INFO - PROCESS | 29882 | Values: 143.0 136.0 133.0 137.0 141.0 141.0 131.0 129.0 169.0 149.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.25 (1.3%) stddev-sans-first:2.29 09:31:14 INFO - PROCESS | 29882 | Values: 169.0 166.0 164.0 166.0 168.0 166.0 171.0 165.0 170.0 167.0 09:31:14 INFO - PROCESS | 29882 | 09:31:14 INFO - PROCESS | 29882 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.50 stddev:2.38 (3.6%) stddev-sans-first:2.40 09:31:14 INFO - PROCESS | 29882 | Values: 69.0 66.0 66.0 66.0 67.0 70.0 66.0 64.0 71.0 64.0 09:31:14 INFO - PROCESS | 29882 | -------- Summary: end -------- 09:31:14 INFO - PROCESS | 29882 | 09:31:16 INFO - TEST-INFO | 29882: exit 0 09:31:16 INFO - TEST-OK | kraken | took 62176ms 09:31:16 INFO - SUITE-END | took 1180s 09:31:16 INFO - Completed test suite (00:19:41) 09:31:16 INFO - javascript benchmark 09:31:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.648715397729, 3090.3664748726515, 3097.798643088198, 3130.1705048162225, 3145.245141166121, 5278.996990571789, 5320.494553017462, 5340.839774806752, 5342.465753424655, 5363.678179309244, 5307.708076055816, 5377.892442151155, 5387.78448862045, 5395.81114660987, 5430.56555475561, 4830.9275360869615, 4857.417109946811, 4877.219644856824, 4899.363082799231, 4942.258661200813, 4914.410270767513, 4973.229149481836, 4978.850634480971, 4982.551570358667, 5030.547250747432, 4787.497312782142, 4905.3623029006, 4918.9508104919205, 4973.552380285773, 4983.700977941317, 4810.460652591172, 4810.494898035691, 4865.464798872121, 4868.975655121737, 4890.046440944017, 4803.18345881201, 4822.324874517571, 4822.7829747661435, 4842.2736589511505, 4878.512148785093, 5318.308619879391, 5319.308489896327, 5325.360956685184, 5334.3598768147685, 5341.412444631087, 5566.499015088621, 5578.0517312057045, 5582.469665381782, 5672.234248376458, 5713.485786279213, 5699.059655156894, 5709.800156994485, 5718.656880587178, 5720.628159169641, 5721.284839395075, 5323.707196104251, 5425.565954723613, 5442.428545002796, 5449.700266485382, 5451.618386712932, 5812.854678633068, 5841.094630332305, 5854.18041474194, 5880.764769409261, 5882.3529411764675, 5663.943360566342, 5671.064274394761, 5671.177679236487, 5672.8865422691315, 5674.035413979634, 5572.108462645957, 5608.523275521545, 5637.548996080303, 5641.379448260688, 5717.428257174332, 5602.915956260659, 5629.887402252014, 5632.014397480424, 5678.148277758287, 5701.116326969326, 5627.943720562824, 5650.604457688005, 5657.60396772222, 5661.2923384576925, 5664.150377443334, 4348.5216626171095, 4355.412019377407, 4368.148211098805, 4377.846775362847, 4413.404190434315, 3957.7229593928723, 3972.940405893914, 3987.182627561343, 4017.2367250222364, 4020.8592699255387, 5365.222042803772, 5390.407055223922, 5412.91880621791, 5491.478309560585, 5493.368262649847, 5758.7696492140685, 5764.423557644202, 5776.42235776419, 5814.476697097281, 5815.2149459822385, 5239.1355426354985, 5295.4969277918535, 5368.0, 5410.621256512007, 5412.107002344569, 4843.564079232886, 4884.780184891689, 4967.776450059756, 4976.353074100416, 5020.397552293748, 5836.620619659709, 5868.413158684098, 5881.206037185011, 5905.232319798399, 5955.7915472957375, 5681.488666020078, 5705.686187259741, 5712.25740653729, 5712.828615141552, 5727.971360143172, 5795.913061304168, 5821.155932389865, 5872.442117998783, 5879.5002424794375, 5887.028640274478, 5549.889002220014, 5627.380988091389, 5630.85922851932, 5632.324121105494, 5634.23937768396, 5733.31200255972, 5735.56983226268, 5736.68448235351, 5741.971290143522, 5742.310922689304, 5599.916001260066, 5604.8318550443555, 5609.691466969356, 5610.635308704921, 5611.382747897646, 5559.916601251065, 5595.272614560081, 5600.775968961196, 5608.1027035675725, 5610.5231055360755, 5529.281193444987, 5536.557075433875, 5553.805616803393, 5554.666719996813, 5555.416681248411, 5514.228008078792, 5622.24099746529, 5634.4365563443325, 5637.830865074055, 5648.265725455664, 5533.059379905506, 5547.861303467445, 5558.805441809679, 5559.610827242054, 5559.6664200148125], "value": 5276.054522673612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.5256879083988, 597.8565144365353, 597.9431953964372, 609.4362714489097, 615.1388056720591, 39883.60116398839, 41434.58565414345, 41489.79255103728, 44480.55519444805, 44497.7775111124, 42660.44037357761, 42792.14415711692, 43096.78451607745, 43108.35337469933, 43157.35263971035, 7810.0, 8413.453125546836, 8561.914380856175, 8567.657293708275, 8569.485830850137, 7766.0, 8004.799880002988, 8013.358931285512, 9866.0, 10140.746481337952, 9843.753906152331, 9880.061394167542, 9884.654037108703, 9886.159676427513, 9907.702768916944, 10179.338343007681, 10218.386896786216, 10219.284650074498, 10224.335418197794, 10228.028337307944, 10005.649802256887, 10064.69805905824, 10080.647177348757, 10084.042016008467, 10089.091981721607, 39349.40975885364, 39545.20909581821, 39547.8022609888, 39665.60334396648, 39710.20579588392, 3628.455731640275, 3701.611330810253, 3901.941470877911, 4011.378525275355, 4068.2066996935314, 29903.364978428377, 30299.242518936982, 30433.847830760704, 30926.0, 32167.195820104684, 4071.816768245436, 4114.0948991221885, 4135.60640435601, 4147.875563733093, 4150.65181086269, 325232.0, 326753.0, 327020.0, 327244.36377818196, 330374.0, 54284.728576356865, 54555.0, 54608.0, 54680.0, 54694.45305546893, 57244.42755572391, 57420.42579574235, 57431.712841435525, 57503.67459579443, 57774.133387999216, 15557.533274001798, 16218.75671864923, 16306.836931630769, 16334.591635208977, 16335.836641633668, 11897.286162830258, 12316.876831231573, 12328.568500102632, 12330.445129969174, 12332.876671233353, 13990.370433330525, 14304.141751494944, 15198.924005380124, 15475.0, 15622.9218853905, 6086.421789929949, 6143.600665956787, 6154.446099850946, 6163.0, 6236.220472440945, 924.1451657217048, 987.4470296633908, 1011.2920955331298, 1059.6344261229863, 1109.8945600167972, 30381.240468988453, 30837.845810770803, 30892.382152356826, 30926.845365773028, 31107.222319442197, 1097.2100087936674, 1142.2917790969486, 1158.7103224193952, 1174.1155445510005, 1205.0600531585378, 16110.516684499484, 16234.188290585424, 16325.755113673304, 16402.671946561, 16679.0, 457797.0, 460415.0, 461758.0, 462170.0, 463942.0, 54147.458525414244, 54300.0, 54424.45575544273, 54445.45554544483, 54541.0, 1285.2802430638872, 1339.6249050265942, 1348.4875747216192, 1356.2947267420886, 1375.5048182654236, 28795.5680664786, 29352.412951741273, 29994.0, 30552.388952220386, 30673.233169170948, 48387.27419088646, 51135.74432127964, 52491.737541312046, 52515.212271816716, 57270.0, 470863.0, 472794.0, 474071.0, 474116.0, 474642.0, 27054.458910822068, 27202.31994200161, 27517.174484765488, 27586.448271034067, 27593.58609620817, 37201.0, 37349.0, 46427.30359044685, 47950.0, 48236.03527929492, 38213.0, 38394.42408363821, 38686.613133868304, 39453.605463944994, 40138.39792403175, 37104.175885368284, 38293.234135317696, 39019.0, 39044.804775975936, 39382.81851544458, 38121.80939095287, 38204.0, 38674.80662596669, 38745.93167717349, 39106.4134037984, 33229.50155747714, 35584.0, 36320.55191721263, 36412.81793591121, 36437.0, 26576.601350979363, 27286.590701139103, 27298.181054568395, 27374.863125684245, 27634.309142271602], "value": 19524.574259859855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.27744510978044, 393.3804258293189, 399.4268225096986, 404.64391335624646, 414.26172996493176, 13251.867481325184, 15205.695886082272, 15898.523044308675, 18813.341533046347, 19307.227710891548, 17885.552861178443, 18130.818691813078, 18316.81683183171, 18498.53753656159, 18514.907425462887, 5278.683279003253, 5482.205080263359, 5484.369297530775, 5485.451454854503, 5488.670679759207, 5123.897522049557, 5370.8925821483745, 5405.756740946647, 5408.0, 5408.675479471224, 5385.596080293974, 5388.568914486851, 5388.811391601294, 5394.703291318976, 5397.568194544447, 5181.35233095863, 5255.605829562779, 5295.523402893758, 5306.575473962073, 5310.256564081013, 4759.071980963713, 4763.737994410306, 4765.09463201994, 4768.284757286435, 4768.380110585636, 20895.164193432243, 21650.133994640197, 21761.564768704695, 21813.345599632037, 21829.454263643376, 2385.1651921827397, 2448.0207916833233, 2575.626534152556, 2831.065748303055, 2896.348321627638, 13456.93271533646, 13465.528706495226, 13528.729425411437, 13945.860541394659, 14275.786558142467, 1842.8065053169491, 1853.8426859416152, 1899.6390685769659, 1904.7047707605343, 1911.0922311901875, 76743.0, 81000.0, 81008.0, 81683.0, 81835.0, 9815.263855210887, 10120.493975301206, 10503.94748026255, 10610.0, 10780.46097695112, 13452.663683407993, 13462.798058028942, 13466.797998030037, 13489.2580908051, 13502.257375844423, 7179.89230161548, 7424.703011879572, 7505.662245198979, 7731.188225236382, 7740.148583655793, 6940.618265995317, 6965.233824279325, 7032.5428847125795, 7176.246494118147, 7261.164966028974, 6355.650439225795, 6359.395857393541, 6404.455621272151, 6415.807525774234, 6454.774082907075, 1812.6284111757059, 1885.6888613378912, 1887.7262796894374, 1928.5757133430623, 1955.1885967323685, 883.8055627761883, 979.5591983607405, 1024.8104100741239, 1034.7620047388991, 1036.3108532825738, 18153.717294192906, 18568.81431185698, 19228.0, 19276.0, 19471.610567788564, 991.3902949685892, 1011.5599714124379, 1013.4729940430934, 1027.4246422003703, 1049.5486940615608, 10308.793824123475, 10801.297915635458, 10844.945775271073, 10903.29128606638, 10955.287906286067, 422393.0, 422587.0, 424699.0, 429707.0, 432054.0, 76448.0, 79249.0, 80366.0, 80569.0, 81181.59409202916, 993.2252842782641, 1000.8748906386702, 1049.464772965793, 1051.0960573906434, 1053.3206082077018, 15963.600909977344, 16214.189290535662, 16541.338346466488, 16600.50198494047, 16796.58008549796, 39347.409788853765, 39503.0, 41020.79489602652, 41690.16619667529, 42582.5741742591, 50481.0, 51623.48376516337, 52126.73936630293, 58211.417885822295, 60342.39657603368, 7644.197359277196, 7862.52824830512, 7890.802729931568, 7917.0, 8137.308328792119, 10192.490375481344, 12047.578334758242, 12190.878091218974, 12412.875871241173, 12438.440270187682, 10751.516181771683, 10756.892431075903, 10768.138548916224, 11177.55289788432, 11359.772804543698, 10818.1886358525, 10887.673369798918, 10960.068394186583, 11018.338899666045, 11182.888171118184, 10928.562857485611, 11118.166137539554, 11570.710732231424, 11689.181757276909, 12019.978301844092, 7921.722739704313, 8113.6754529818145, 8270.2556769891, 8311.625976830936, 8399.790005249917, 9553.23574114083, 11187.832182517432, 11250.493727782434, 11520.769584608093, 11536.134789890624], "value": 8904.346336091725, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.1100007994241, 1407.957761267162, 1433.2833583208396, 1527.2821773766325, 1719.4755599542134, 7263.60050197239, 7846.411519136066, 8056.315213206884, 8066.0, 8316.542590157538, 8147.063087744911, 8190.344772418207, 8192.836143277145, 8205.056418511873, 8206.958965205165, 1907.9904600477048, 2158.093600687715, 2187.3219302016428, 2188.3325585696357, 2190.5071358944188, 1998.0, 2203.173809821317, 2258.1983395894467, 2261.061659411342, 2291.541691661666, 2209.9558008839813, 2212.214663794354, 2227.654713519407, 2229.7435794883554, 2239.2050821958214, 2160.8379371547117, 2164.945876353088, 2168.3494951514563, 2169.0889826273005, 2176.706144670465, 2135.177956486764, 2161.384005558424, 2194.8353873459555, 2200.3618950504333, 2204.206485665159, 8192.836143277102, 8304.418690691655, 8307.750767476986, 8313.0, 8325.375596830261, 4865.951340486586, 4984.87537811554, 4991.825286115005, 4992.076465853793, 4996.975015124937, 3167.509036099408, 3297.686719761619, 3342.331533693271, 3381.5942086949476, 3415.077928959192, 1006.1726548398949, 1103.757173421846, 1104.127739086129, 2065.1842522203647, 2101.0860275780083, 11349.602763903224, 11461.770764584662, 11529.884701152965, 11590.304581725124, 11590.652280431601, 4083.4487344208746, 4246.280256496124, 4303.07483890965, 4365.345198220292, 4366.8908277292685, 8972.19250267479, 9001.324900632479, 9004.54977251148, 9011.729648110568, 9015.27877769777, 4987.825426110068, 4987.850364489072, 4988.326575912205, 4988.6507944444265, 4992.176290912032, 3722.4147819365244, 4425.800838962255, 4536.705114167635, 4586.954130458719, 4590.104929538775, 3500.824958752052, 3510.104923244556, 3570.321638888603, 3587.5694916610173, 3609.0796846803896, 1064.6273804168634, 1074.215822449616, 1077.5312738958578, 1078.6763970808727, 1085.6254592165849, 829.742779738283, 944.7449188719007, 946.711253067821, 952.9952350238204, 973.6543527047796, 5493.395726470085, 5504.669719816824, 5583.860403489864, 5630.746616402272, 5652.858678532987, 411.44249541870334, 426.7204980737622, 427.1584977594135, 431.22164493089645, 437.72642098688317, 3760.2291530236234, 3815.904602384963, 3833.5783063862955, 3852.3643598805884, 3905.33609286422, 9716.417014979124, 9940.304178707565, 9980.650677226407, 10065.64770233053, 10070.647527336654, 9812.925509613222, 9990.0, 10008.249381296431, 10009.499525023719, 10014.79970400588, 7686.269804368576, 7755.379569634416, 7792.922070779333, 7803.219678032151, 7804.492707973964, 5441.483059109379, 5494.313210848644, 5506.146547285177, 5600.467955544217, 5610.18652295423, 9035.909640903506, 9179.127982841619, 9255.074492550692, 9277.304202184729, 9440.716778496657, 9418.434893906388, 9492.715218543455, 9521.476318802532, 9542.570584323861, 9723.513824308615, 6785.117934668461, 6936.7225310987, 7033.648317584202, 7418.109826820816, 7502.587357695379, 7635.160132385322, 7880.645370958206, 7920.84158316842, 8061.758147255592, 8069.59652017385, 7040.612766297902, 7208.06295181623, 7240.927590724236, 7417.109946806418, 7464.552126872188, 6994.6852391643515, 7105.715771369294, 7165.104361954755, 7223.783286501413, 7230.42156627458, 6466.3510804746375, 6606.900896486461, 6608.70260838273, 6611.371919667817, 6652.73389064432, 4870.78081486341, 4933.6299777516115, 4957.231629097496, 5066.240063990431, 5120.897582048264, 4961.67749096322, 5002.074616196015, 5124.795673016763, 5224.738763061908, 5226.869328266824], "value": 4281.872115496093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.8670041831385, 314.7434840604905, 317.5522513256309, 325.13027651033485, 328.9819059951702, 5081.64428490005, 5167.509086636773, 5285.233641122039, 5322.520973112419, 5355.893581722601, 5106.183010718286, 5196.142636464979, 5214.165733482643, 5242.344706911636, 5263.842084737455, 4115.444415003966, 4121.835126594932, 4133.627973482386, 4135.338345864662, 4135.937960930596, 4021.517417909855, 4081.979590102045, 4115.464989551354, 4143.523494798107, 4151.730137541065, 4047.7166598338126, 4078.8572399966006, 4080.489938757655, 4093.485410398142, 4095.1809638072355, 4060.8984775380554, 4067.23428068913, 4073.6741060715367, 4080.7347522410946, 4081.1225586498895, 3607.5129857469433, 3613.7109031277437, 3621.167131559756, 3625.1480901988275, 3674.6876515496215, 4962.60299176065, 5079.31429257053, 5092.872678183038, 5132.40977287613, 5161.896762064775, 4660.673752837302, 4669.813207471697, 4680.227762419197, 4685.461171965234, 4694.460137084245, 5438.864028399282, 5439.83680489582, 5476.58925580579, 5492.176173573956, 5504.862378440531, 3651.981740091309, 3841.9807900960595, 3873.4189871519216, 3888.241792850395, 4009.3361255079108, 5650.14667592867, 5650.943490565124, 5666.858328541778, 5690.487856092972, 5695.800646977336, 3945.546262179829, 3951.6641085508054, 3957.5357117866574, 3969.8809035728973, 4026.9597304026584, 4002.6197511236387, 4047.0489434982755, 4059.208454351432, 4096.5903409658795, 4118.320477121301, 4664.673472856867, 4668.346431499593, 4680.251159814414, 4691.014886873726, 4692.202325604655, 5566.912407847058, 5576.442355764391, 5580.888382232332, 5608.887822243614, 5619.656304498817, 1296.3777386854363, 1319.8350206224222, 1339.2098661789591, 1349.379285528648, 1352.8838708065884, 919.3380765848578, 930.5207817973613, 942.3686130292721, 951.9143277105094, 952.1763674421576, 5119.180931051014, 5137.229415587692, 5140.383154021467, 5141.349641754322, 5149.716765577854, 5363.517283444508, 5383.596230282745, 5411.107167317348, 5413.648112872729, 5476.069068258405, 4950.683042361627, 5161.716105614228, 5164.2770012198325, 5178.766955486937, 5215.765290561933, 2918.270432391902, 2927.516959701625, 2932.633420822397, 2942.4262268857797, 2942.514485109976, 5537.723113844372, 5677.9211949729415, 5701.458361455655, 5707.600467967202, 5710.71446427677, 5204.323437953042, 5457.454254574511, 5483.917741233885, 5498.560115190775, 5516.096946553433, 5609.691466969274, 5611.915821262684, 5620.522255608319, 5635.8591035224445, 5639.3645842705155, 5186.740662966912, 5321.3880403754065, 5349.1976203569775, 5435.592330575144, 5448.945510544844, 5368.382635996911, 5379.67721936685, 5382.542483888913, 5386.569074473954, 5386.919196212139, 5581.972090139523, 5595.132754423071, 5622.762992141886, 5625.437456254342, 5627.831165065055, 3752.7373083883867, 3855.9807200963814, 3861.4400911868183, 3863.7442831080575, 3866.2654095721723, 5472.589555783253, 5634.52106570946, 5658.5190258826815, 5677.971610141923, 5689.772409103755, 5436.972815135899, 5438.945610543844, 5486.079872044753, 5531.557475401878, 5539.390667026543, 5517.420670829505, 5525.254090697865, 5551.47481349338, 5593.412691667317, 5604.915926261027, 5335.866603334793, 5466.835994920159, 5498.725063746877, 5539.701024074964, 5545.306836645419, 5572.4706152915405, 5573.331200255995, 5576.191452239484, 5599.216109744558, 5609.0, 5451.264079349237, 5458.426865179099, 5461.15352120422, 5501.129615930603, 5522.199281104297], "value": 4146.1728779463465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.2966441209679, 778.9182135875733, 809.8889620451182, 817.4931542443682, 835.4152093534525, 15049.548513544598, 15693.058416495018, 15706.0, 15707.84292157078, 15722.641603759414, 14691.33888974996, 14822.0, 14848.034877732967, 14852.554423367315, 14874.553763387088, 673.0543586261286, 684.0389253099403, 770.1874522378888, 783.8353945671416, 785.9764207073782, 783.0994356490025, 797.7287722174459, 797.7566842113154, 798.6286376834771, 799.4443861516248, 842.8356470488258, 849.9490030598184, 850.7958090058372, 852.2244757270885, 852.3224036890687, 713.8072720365524, 728.726727477196, 826.8511667899776, 846.8179341441589, 848.2154007543029, 614.9569530132892, 618.2179542878263, 618.3847072163227, 618.5577312221739, 619.0, 15020.624484387869, 15051.473198438112, 15123.773143402857, 15160.469383571522, 15180.240987950558, 13625.795613065811, 13753.931230343884, 13785.448582056706, 13818.239996800172, 13991.510297139552, 12922.547710830077, 13015.544455943997, 13038.478460861554, 13390.732185356239, 13559.118657287245, 4257.939562486045, 4377.518472968003, 4386.429764130643, 4414.933775993362, 4433.778311084465, 74325.62837185833, 77274.0, 78121.60939195324, 79202.20797792006, 79368.60315698442, 11036.668899933014, 11300.773984520429, 11434.428278586038, 11721.35532545701, 11853.0, 22150.778492215195, 22181.778182218295, 22206.66689999651, 22210.11159553632, 22341.664875026887, 8118.918810811934, 8168.183181681784, 8192.374781958635, 8250.47366237248, 8438.36712246584, 4512.323151527297, 4595.287730401793, 4602.401687780567, 4661.230896902041, 4694.929576056362, 3320.3193345364143, 3427.2288735034917, 3428.160100775326, 3432.4508078707786, 3435.055359776082, 555.1672491263105, 562.1483452569361, 577.4946921443736, 583.8190161050088, 592.5644651181378, 560.5823661372303, 611.1871211288975, 618.2797041446754, 682.5461068389565, 694.4964900447236, 9023.187913087853, 9370.156685898302, 9388.776361253393, 9647.807043859084, 9727.416355018722, 645.7998020613625, 652.774792696519, 670.902719105727, 680.5848432456198, 700.2122612061431, 4351.782410879507, 4627.884302892455, 4740.5733483986605, 4752.643551733634, 4804.759762011955, 87622.0, 87670.0, 87891.56054219816, 88771.55614222017, 90872.0, 73760.82946313906, 75198.0, 75367.62316188494, 75649.0, 76080.61959690276, 65484.0, 67570.0, 70072.29927700658, 71024.2897571028, 71382.28617713756, 9018.954905225432, 9217.539123043955, 9246.583903724215, 9368.297377696836, 9414.62341506332, 39124.21751564896, 41262.79368603258, 43600.34599481074, 44083.33874991942, 45416.54583454123, 57654.4234557649, 58778.0, 61824.38175618366, 62150.68924655348, 63218.36781632309, 11900.76198476043, 11948.103892208293, 12016.819747703617, 12087.576934807239, 12200.328981906081, 12582.043343653251, 12790.929181515814, 12832.422540985492, 12873.485060597472, 13192.406341714453, 12005.39973001329, 12378.938105309417, 12388.56640017595, 12521.87478125207, 12698.111132220656, 11831.17181797266, 12600.684982875502, 12999.220046797222, 13121.868781312065, 13138.540151095049, 11603.477843497232, 11676.532938682358, 11875.346855923008, 12190.634280971584, 12741.17182383142, 9780.853287200556, 10055.195584353089, 10374.481275936323, 10485.623827624808, 10576.20678449104, 8327.625256863335, 8983.955080224558, 9065.311106127223, 9443.905560944302, 9962.452065136487], "value": 7106.950248091509, "unit": "score"}], "name": "dromaeo_css", "value": 6980.996704253482, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 112.0, 112.0, 113.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 125.0, 126.0, 167.0, 125.0, 127.0, 124.0, 126.0, 155.0, 129.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 109.0, 78.0, 77.0, 76.0, 76.0, 93.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 108.0, 83.0, 83.0, 83.0, 97.0, 84.0, 83.0, 111.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 97.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 55.0, 50.0, 54.0, 52.0, 54.0, 52.0, 50.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 67.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 80.0, 80.0, 84.0, 95.0, 80.0, 80.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 136.0, 133.0, 137.0, 141.0, 141.0, 131.0, 129.0, 169.0, 149.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 164.0, 166.0, 168.0, 166.0, 171.0, 165.0, 170.0, 167.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 66.0, 67.0, 70.0, 66.0, 64.0, 71.0, 64.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1510.2000000000003, "alertThreshold": 2.0}]} 09:31:16 INFO - Return code: 0 09:31:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:31:16 INFO - # TBPL SUCCESS # 09:31:16 INFO - Running post-action listener: _resource_record_post_action 09:31:16 INFO - [mozharness: 2016-11-27 17:31:16.627480Z] Finished run-tests step (success) 09:31:16 INFO - Running post-run listener: _resource_record_post_run 09:31:16 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 8192; Write bytes: 405454848; Read time: 164; Write time: 289932 09:31:16 INFO - TinderboxPrint: CPU usage
13.1% 09:31:16 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 164 09:31:16 INFO - TinderboxPrint: I/O write bytes / time
405,454,848 / 289,932 09:31:16 INFO - TinderboxPrint: CPU idle
8,303.8 (86.9%) 09:31:16 INFO - TinderboxPrint: CPU user
1,202.5 (12.6%) 09:31:16 INFO - TinderboxPrint: Swap in / out
0 / 0 09:31:16 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114503680; Read time: 0; Write time: 67716 09:31:16 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289812480; Read time: 144; Write time: 222184 09:31:17 INFO - Running post-run listener: _upload_blobber_files 09:31:17 INFO - Blob upload gear active. 09:31:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:17 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 09:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:17 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:21 INFO - (blobuploader) - INFO - Done attempting. 09:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:22 INFO - (blobuploader) - INFO - Done attempting. 09:31:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:31:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:22 INFO - (blobuploader) - INFO - Done attempting. 09:31:22 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:22 INFO - Return code: 0 09:31:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/91407547ac84daced4ba485b6ea8125bb19c39a7ae0839afe0f665aa810480f0428aa0f8483ae6782e622e72cf077eac9f8595c9353a6a5cd9f412306f9feedd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cee5a7c25d1385578d0cd6c397a06275f0987477574a4d4f60657a7b49a402144a81b9ca4b4bd8f93d33c2edbf9fa9b9f79989be2dc626940411455a36482c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf00d7814791dbfe596c847f2f77635657eb80e0bff93ac19212b2876754491c04d548e40bb173ad3a2a9b2cfeb2a2b6e9ab7574bf5cd420ce9ecdd8e4145ade"} 09:31:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:22 INFO - Contents: 09:31:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/91407547ac84daced4ba485b6ea8125bb19c39a7ae0839afe0f665aa810480f0428aa0f8483ae6782e622e72cf077eac9f8595c9353a6a5cd9f412306f9feedd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cee5a7c25d1385578d0cd6c397a06275f0987477574a4d4f60657a7b49a402144a81b9ca4b4bd8f93d33c2edbf9fa9b9f79989be2dc626940411455a36482c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf00d7814791dbfe596c847f2f77635657eb80e0bff93ac19212b2876754491c04d548e40bb173ad3a2a9b2cfeb2a2b6e9ab7574bf5cd420ce9ecdd8e4145ade"} 09:31:22 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:22 INFO - Copying logs to upload dir... 09:31:22 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.272514 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-11-27 09:31:20.590666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 09:31:20.594782) ========= 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=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/91407547ac84daced4ba485b6ea8125bb19c39a7ae0839afe0f665aa810480f0428aa0f8483ae6782e622e72cf077eac9f8595c9353a6a5cd9f412306f9feedd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cee5a7c25d1385578d0cd6c397a06275f0987477574a4d4f60657a7b49a402144a81b9ca4b4bd8f93d33c2edbf9fa9b9f79989be2dc626940411455a36482c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf00d7814791dbfe596c847f2f77635657eb80e0bff93ac19212b2876754491c04d548e40bb173ad3a2a9b2cfeb2a2b6e9ab7574bf5cd420ce9ecdd8e4145ade"} build_url:https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/E6vne_cER1-hAZFarf1uTQ/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/Mozilla-Inbound/sha512/91407547ac84daced4ba485b6ea8125bb19c39a7ae0839afe0f665aa810480f0428aa0f8483ae6782e622e72cf077eac9f8595c9353a6a5cd9f412306f9feedd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cee5a7c25d1385578d0cd6c397a06275f0987477574a4d4f60657a7b49a402144a81b9ca4b4bd8f93d33c2edbf9fa9b9f79989be2dc626940411455a36482c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cf00d7814791dbfe596c847f2f77635657eb80e0bff93ac19212b2876754491c04d548e40bb173ad3a2a9b2cfeb2a2b6e9ab7574bf5cd420ce9ecdd8e4145ade"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 09:31:20.623380) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:31:20.624134) ========= 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=fa44defc139c4ba8574fe040000001db-1480261920.685598-58269759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 09:31:20.705921) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 09:31:20.706615) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 09:31:20.707243) ========= ========= Total master_lag: 0.19 =========