builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1486088729.44 results: success (0) buildid: 20170202163809 builduid: 9ce2b59662434c1c8211010d2f57c36f revision: 9759594e223f25075dde334fe96af323bf33d144 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.443872) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.444431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.444744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.461489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.461915) ========= 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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-02 18:25:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.5M=0s 2017-02-02 18:25:29 (40.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.335336 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.811316) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.811646) ========= 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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:29.862878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 18:25:29.863454) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9759594e223f25075dde334fe96af323bf33d144 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9759594e223f25075dde334fe96af323bf33d144 --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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-02 18:25:29,906 truncating revision to first 12 chars 2017-02-02 18:25:29,906 Setting DEBUG logging. 2017-02-02 18:25:29,906 attempt 1/10 2017-02-02 18:25:29,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9759594e223f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 18:25:31,194 unpacking tar archive at: mozilla-beta-9759594e223f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.569544 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 18:25:31.447155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:31.447476) ========= script_repo_revision: 9759594e223f25075dde334fe96af323bf33d144 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:31.447889) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:31.448154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 18:25:31.458043) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-02-02 18:25:31.458333) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:25:31 INFO - MultiFileLogger online at 20170202 18:25:31 in /builds/slave/test 18:25:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:25:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:25:31 INFO - {'append_to_log': False, 18:25:31 INFO - 'base_work_dir': '/builds/slave/test', 18:25:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:25:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:25:31 INFO - 'branch': 'Mozilla-Beta', 18:25:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:25:31 INFO - 'config_files': ('talos/linux_config.py',), 18:25:31 INFO - 'default_actions': ('clobber', 18:25:31 INFO - 'read-buildbot-config', 18:25:31 INFO - 'download-and-extract', 18:25:31 INFO - 'populate-webroot', 18:25:31 INFO - 'create-virtualenv', 18:25:31 INFO - 'install', 18:25:31 INFO - 'run-tests'), 18:25:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:25:31 INFO - 'download_minidump_stackwalk': True, 18:25:31 INFO - 'download_symbols': 'ondemand', 18:25:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:25:31 INFO - 'tooltool.py': '/tools/tooltool.py', 18:25:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:25:31 INFO - '/tools/misc-python/virtualenv.py')}, 18:25:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:25:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:25:31 INFO - 'installer_path': 'installer.exe', 18:25:31 INFO - 'log_level': 'info', 18:25:31 INFO - 'log_name': 'talos', 18:25:31 INFO - 'log_to_console': True, 18:25:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:25:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:25:31 INFO - 'opt_config_files': (), 18:25:31 INFO - 'pip_index': False, 18:25:31 INFO - 'sps_profile': False, 18:25:31 INFO - 'sps_profile_interval': 0, 18:25:31 INFO - 'suite': 'dromaeojs', 18:25:31 INFO - 'system_bits': '32', 18:25:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:25:31 INFO - 'title': 'talos-linux64-ix-020', 18:25:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:25:31 INFO - 'use_talos_json': True, 18:25:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:25:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:25:31 INFO - 'work_dir': 'build'} 18:25:31 INFO - [mozharness: 2017-02-03 02:25:31.600803Z] Running clobber step. 18:25:31 INFO - Running pre-action listener: _resource_record_pre_action 18:25:31 INFO - Running main action method: clobber 18:25:31 INFO - rmtree: /builds/slave/test/build 18:25:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:25:32 INFO - Running post-action listener: _resource_record_post_action 18:25:32 INFO - [mozharness: 2017-02-03 02:25:32.063332Z] Finished clobber step (success) 18:25:32 INFO - [mozharness: 2017-02-03 02:25:32.063430Z] Running read-buildbot-config step. 18:25:32 INFO - Running pre-action listener: _resource_record_pre_action 18:25:32 INFO - Running main action method: read_buildbot_config 18:25:32 INFO - Using buildbot properties: 18:25:32 INFO - { 18:25:32 INFO - "project": "", 18:25:32 INFO - "product": "firefox", 18:25:32 INFO - "script_repo_revision": "production", 18:25:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:25:32 INFO - "repository": "", 18:25:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:25:32 INFO - "buildid": "20170202163809", 18:25:32 INFO - "pgo_build": "True", 18:25:32 INFO - "basedir": "/builds/slave/test", 18:25:32 INFO - "buildnumber": 127, 18:25:32 INFO - "slavename": "talos-linux64-ix-020", 18:25:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:25:32 INFO - "platform": "ubuntu64_hw", 18:25:32 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:25:32 INFO - "branch": "mozilla-beta", 18:25:32 INFO - "repo_path": "releases/mozilla-beta", 18:25:32 INFO - "revision": "9759594e223f25075dde334fe96af323bf33d144", 18:25:32 INFO - "stage_platform": "linux64-pgo", 18:25:32 INFO - "builduid": "9ce2b59662434c1c8211010d2f57c36f", 18:25:32 INFO - "slavebuilddir": "test" 18:25:32 INFO - } 18:25:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 18:25:32 INFO - Running post-action listener: _resource_record_post_action 18:25:32 INFO - [mozharness: 2017-02-03 02:25:32.065857Z] Finished read-buildbot-config step (success) 18:25:32 INFO - [mozharness: 2017-02-03 02:25:32.065945Z] Running download-and-extract step. 18:25:32 INFO - Running pre-action listener: _resource_record_pre_action 18:25:32 INFO - Running main action method: download_and_extract 18:25:32 INFO - mkdir: /builds/slave/test/build/tests 18:25:32 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')]} 18:25:32 INFO - trying https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:25:32 INFO - Downloading https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:25:32 INFO - Downloaded 1327 bytes. 18:25:32 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:25:32 INFO - Using the following test package requirements: 18:25:32 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:25:32 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:25:32 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 18:25:32 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'jsshell-linux-x86_64.zip'], 18:25:32 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:25:32 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:25:32 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 18:25:32 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 18:25:32 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:25:32 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:25:32 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:25:32 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:25:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:25:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:25:32 INFO - Fetch https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:25:33 INFO - Http code: 200 18:25:33 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 18:25:33 INFO - x-amz-cf-id: u55bdtDCipjHryC1otlxOVTL8OtRjtW1wpO1hricNqnlTbgchn_VqQ== 18:25:33 INFO - x-amz-version-id: Th8hLpevYBXprPsi7dxubjKdQBqnSn7b 18:25:33 INFO - Expected file size: 21956100 18:25:33 INFO - Obtained file size: 21956100 18:25:37 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:25:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:25:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:25:37 INFO - Fetch https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:25:38 INFO - Http code: 200 18:25:38 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 18:25:38 INFO - x-amz-cf-id: lifry_4-gC_iOgIiW2qjmCuCab830Igp-YOMx6d6c2mUACKH5vFyKw== 18:25:38 INFO - x-amz-version-id: Th8hLpevYBXprPsi7dxubjKdQBqnSn7b 18:25:38 INFO - Expected file size: 21956100 18:25:38 INFO - Obtained file size: 21956100 18:25:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 18:25:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:25:42 INFO - Fetch https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 18:25:43 INFO - Http code: 200 18:25:43 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 18:25:43 INFO - x-amz-cf-id: CzETw7vQAfPDUdrl4qXqeyweSm7COP1fuZbhJqWMxHOFKTjeas5lbA== 18:25:43 INFO - x-amz-version-id: HDNGH6ffVV_KXNDWQbk0LoF8B9_9gUy3 18:25:43 INFO - Expected file size: 14403005 18:25:43 INFO - Obtained file size: 14403005 18:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:43 INFO - trying https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:25:44 INFO - Downloading https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:25:45 INFO - Downloaded 57691447 bytes. 18:25:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:25:45 INFO - mkdir: /builds/slave/test/properties 18:25:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:25:45 INFO - Writing to file /builds/slave/test/properties/build_url 18:25:45 INFO - Contents: 18:25:45 INFO - build_url:https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:25:46 INFO - Running post-action listener: _resource_record_post_action 18:25:46 INFO - Running post-action listener: set_extra_try_arguments 18:25:46 INFO - [mozharness: 2017-02-03 02:25:46.072446Z] Finished download-and-extract step (success) 18:25:46 INFO - [mozharness: 2017-02-03 02:25:46.072674Z] Running populate-webroot step. 18:25:46 INFO - Running pre-action listener: _resource_record_pre_action 18:25:46 INFO - Running main action method: populate_webroot 18:25:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:25:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:25:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:25:46 INFO - '--tppagecycles', 18:25:46 INFO - '7'], 18:25:46 INFO - 'tests': ['tsvgm']}}, 18:25:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:25:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:25:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['damp', 'tps']}, 18:25:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'tests': ['damp', 'tps']}, 18:25:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['dromaeo_dom']}, 18:25:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['basic_compositor_video']}, 18:25:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:25:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['a11yr', 18:25:46 INFO - 'ts_paint', 18:25:46 INFO - 'tpaint', 18:25:46 INFO - 'sessionrestore', 18:25:46 INFO - 'sessionrestore_no_auto_restore', 18:25:46 INFO - 'tabpaint']}, 18:25:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['tsvgx', 18:25:46 INFO - 'tsvgr_opacity', 18:25:46 INFO - 'tart', 18:25:46 INFO - 'tscrollx', 18:25:46 INFO - 'cart']}, 18:25:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:46 INFO - 'tsvgr_opacity', 18:25:46 INFO - 'tart', 18:25:46 INFO - 'tscrollx', 18:25:46 INFO - 'cart']}, 18:25:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'talos_options': ['--disable-e10s'], 18:25:46 INFO - 'tests': ['tp5o']}, 18:25:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'tests': ['tp5o']}, 18:25:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'talos_options': ['--disable-e10s', 18:25:46 INFO - '--xperf_path', 18:25:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:46 INFO - 'tests': ['tp5n']}, 18:25:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:46 INFO - 'talos_options': ['--xperf_path', 18:25:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:46 INFO - 'tests': ['tp5n']}}, 18:25:46 INFO - 'talos.zip': {'path': '', 18:25:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:25:46 INFO - Running post-action listener: _resource_record_post_action 18:25:46 INFO - [mozharness: 2017-02-03 02:25:46.091335Z] Finished populate-webroot step (success) 18:25:46 INFO - [mozharness: 2017-02-03 02:25:46.091423Z] Running create-virtualenv step. 18:25:46 INFO - Running pre-action listener: _resource_record_pre_action 18:25:46 INFO - Running main action method: create_virtualenv 18:25:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:46 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 18:25:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:25:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:46 INFO - Using real prefix '/usr' 18:25:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:46 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:48 INFO - Installing pip.................done. 18:25:48 INFO - Return code: 0 18:25:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:48 INFO - Reading from file tmpfile_stdout 18:25:48 INFO - Output received: 18:25:48 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) 18:25:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:48 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')]} 18:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:48 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')]} 18:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:48 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:48 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 18:25:48 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 18:25:48 INFO - Using env: {'DISPLAY': ':0', 18:25:48 INFO - 'HOME': '/home/cltbld', 18:25:48 INFO - 'LANG': 'en_US.UTF-8', 18:25:48 INFO - 'LANGUAGE': 'en_US:en', 18:25:48 INFO - 'LOGNAME': 'cltbld', 18:25:48 INFO - 'MAIL': '/var/mail/cltbld', 18:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:48 INFO - 'MOZ_NO_REMOTE': '1', 18:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:48 INFO - 'NO_EM_RESTART': '1', 18:25:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:48 INFO - 'PWD': '/builds/slave/test', 18:25:48 INFO - 'SHELL': '/bin/bash', 18:25:48 INFO - 'SHLVL': '1', 18:25:48 INFO - 'TERM': 'linux', 18:25:48 INFO - 'TMOUT': '86400', 18:25:48 INFO - 'USER': 'cltbld', 18:25:48 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:48 INFO - '_': '/tools/buildbot/bin/python'} 18:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:49 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 18:25:49 INFO - Cleaning up... 18:25:49 INFO - Return code: 0 18:25:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:49 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:49 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 18:25:49 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 18:25:49 INFO - Using env: {'DISPLAY': ':0', 18:25:49 INFO - 'HOME': '/home/cltbld', 18:25:49 INFO - 'LANG': 'en_US.UTF-8', 18:25:49 INFO - 'LANGUAGE': 'en_US:en', 18:25:49 INFO - 'LOGNAME': 'cltbld', 18:25:49 INFO - 'MAIL': '/var/mail/cltbld', 18:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:49 INFO - 'MOZ_NO_REMOTE': '1', 18:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:49 INFO - 'NO_EM_RESTART': '1', 18:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:49 INFO - 'PWD': '/builds/slave/test', 18:25:49 INFO - 'SHELL': '/bin/bash', 18:25:49 INFO - 'SHLVL': '1', 18:25:49 INFO - 'TERM': 'linux', 18:25:49 INFO - 'TMOUT': '86400', 18:25:49 INFO - 'USER': 'cltbld', 18:25:49 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:49 INFO - '_': '/tools/buildbot/bin/python'} 18:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:49 INFO - Downloading/unpacking psutil>=3.1.1 18:25:49 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 18:25:49 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 18:25:49 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 18:25:49 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 18:25:49 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 18:25:49 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 18:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:51 INFO - Installing collected packages: psutil 18:25:51 INFO - Running setup.py install for psutil 18:25:51 INFO - building 'psutil._psutil_linux' extension 18:25:51 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 18:25:51 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 18:25:51 INFO - building 'psutil._psutil_posix' extension 18:25:51 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 18:25:51 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 18:25:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:51 INFO - Successfully installed psutil 18:25:51 INFO - Cleaning up... 18:25:51 INFO - Return code: 0 18:25:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:51 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')]} 18:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:51 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')]} 18:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:51 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:51 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 18:25:51 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 18:25:51 INFO - Using env: {'DISPLAY': ':0', 18:25:51 INFO - 'HOME': '/home/cltbld', 18:25:51 INFO - 'LANG': 'en_US.UTF-8', 18:25:51 INFO - 'LANGUAGE': 'en_US:en', 18:25:51 INFO - 'LOGNAME': 'cltbld', 18:25:51 INFO - 'MAIL': '/var/mail/cltbld', 18:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:51 INFO - 'MOZ_NO_REMOTE': '1', 18:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:51 INFO - 'NO_EM_RESTART': '1', 18:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:51 INFO - 'PWD': '/builds/slave/test', 18:25:51 INFO - 'SHELL': '/bin/bash', 18:25:51 INFO - 'SHLVL': '1', 18:25:51 INFO - 'TERM': 'linux', 18:25:51 INFO - 'TMOUT': '86400', 18:25:51 INFO - 'USER': 'cltbld', 18:25:51 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:52 INFO - '_': '/tools/buildbot/bin/python'} 18:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:52 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 18:25:52 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 18:25:52 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 18:25:52 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 18:25:52 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 18:25:52 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 18:25:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:54 INFO - Installing collected packages: mozsystemmonitor 18:25:54 INFO - Running setup.py install for mozsystemmonitor 18:25:54 INFO - Successfully installed mozsystemmonitor 18:25:54 INFO - Cleaning up... 18:25:54 INFO - Return code: 0 18:25:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:54 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')]} 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:54 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')]} 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:54 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:54 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 18:25:54 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 18:25:54 INFO - Using env: {'DISPLAY': ':0', 18:25:54 INFO - 'HOME': '/home/cltbld', 18:25:54 INFO - 'LANG': 'en_US.UTF-8', 18:25:54 INFO - 'LANGUAGE': 'en_US:en', 18:25:54 INFO - 'LOGNAME': 'cltbld', 18:25:54 INFO - 'MAIL': '/var/mail/cltbld', 18:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:54 INFO - 'MOZ_NO_REMOTE': '1', 18:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:54 INFO - 'NO_EM_RESTART': '1', 18:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:54 INFO - 'PWD': '/builds/slave/test', 18:25:54 INFO - 'SHELL': '/bin/bash', 18:25:54 INFO - 'SHLVL': '1', 18:25:54 INFO - 'TERM': 'linux', 18:25:54 INFO - 'TMOUT': '86400', 18:25:54 INFO - 'USER': 'cltbld', 18:25:54 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:54 INFO - '_': '/tools/buildbot/bin/python'} 18:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:54 INFO - Downloading/unpacking jsonschema==2.5.1 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:25:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:25:56 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 18:25:56 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 18:25:56 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 18:25:56 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 18:25:56 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 18:25:56 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 18:25:56 INFO - Downloading functools32-3.2.3-2.tar.gz 18:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:25:56 INFO - warning: no files found matching '*.txt' 18:25:56 INFO - no previously-included directories found matching 'build' 18:25:56 INFO - no previously-included directories found matching 'dist' 18:25:56 INFO - no previously-included directories found matching '.git*' 18:25:56 INFO - Installing collected packages: jsonschema, functools32 18:25:56 INFO - Running setup.py install for functools32 18:25:57 INFO - warning: no files found matching '*.txt' 18:25:57 INFO - no previously-included directories found matching 'build' 18:25:57 INFO - no previously-included directories found matching 'dist' 18:25:57 INFO - no previously-included directories found matching '.git*' 18:25:57 INFO - Successfully installed jsonschema functools32 18:25:57 INFO - Cleaning up... 18:25:57 INFO - Return code: 0 18:25:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:25:57 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')]} 18:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:57 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')]} 18:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:57 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:57 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 18:25:57 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 18:25:57 INFO - Using env: {'DISPLAY': ':0', 18:25:57 INFO - 'HOME': '/home/cltbld', 18:25:57 INFO - 'LANG': 'en_US.UTF-8', 18:25:57 INFO - 'LANGUAGE': 'en_US:en', 18:25:57 INFO - 'LOGNAME': 'cltbld', 18:25:57 INFO - 'MAIL': '/var/mail/cltbld', 18:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:57 INFO - 'MOZ_NO_REMOTE': '1', 18:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:57 INFO - 'NO_EM_RESTART': '1', 18:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:57 INFO - 'PWD': '/builds/slave/test', 18:25:57 INFO - 'SHELL': '/bin/bash', 18:25:57 INFO - 'SHLVL': '1', 18:25:57 INFO - 'TERM': 'linux', 18:25:57 INFO - 'TMOUT': '86400', 18:25:57 INFO - 'USER': 'cltbld', 18:25:57 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:57 INFO - '_': '/tools/buildbot/bin/python'} 18:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:25:57 INFO - Cleaning up... 18:25:57 INFO - Return code: 0 18:25:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:25:57 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')]} 18:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:57 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')]} 18:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:57 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:25:57 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 18:25:57 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 18:25:57 INFO - Using env: {'DISPLAY': ':0', 18:25:57 INFO - 'HOME': '/home/cltbld', 18:25:57 INFO - 'LANG': 'en_US.UTF-8', 18:25:57 INFO - 'LANGUAGE': 'en_US:en', 18:25:57 INFO - 'LOGNAME': 'cltbld', 18:25:57 INFO - 'MAIL': '/var/mail/cltbld', 18:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:57 INFO - 'MOZ_NO_REMOTE': '1', 18:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:57 INFO - 'NO_EM_RESTART': '1', 18:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:57 INFO - 'PWD': '/builds/slave/test', 18:25:57 INFO - 'SHELL': '/bin/bash', 18:25:57 INFO - 'SHLVL': '1', 18:25:57 INFO - 'TERM': 'linux', 18:25:57 INFO - 'TMOUT': '86400', 18:25:57 INFO - 'USER': 'cltbld', 18:25:57 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:57 INFO - '_': '/tools/buildbot/bin/python'} 18:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:25:57 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 18:25:57 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 18:25:57 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 18:25:57 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 18:25:57 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 18:25:57 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 18:25:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:25:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:00 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 18:26:00 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 18:26:00 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 18:26:00 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 18:26:00 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 18:26:00 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 18:26:00 INFO - Downloading docopt-0.6.1.tar.gz 18:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:00 INFO - Running setup.py install for blobuploader 18:26:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:00 INFO - Running setup.py install for requests 18:26:00 INFO - Running setup.py install for docopt 18:26:00 INFO - Successfully installed blobuploader requests docopt 18:26:00 INFO - Cleaning up... 18:26:01 INFO - Return code: 0 18:26:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:01 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:26:01 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 18:26:01 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 18:26:01 INFO - Using env: {'DISPLAY': ':0', 18:26:01 INFO - 'HOME': '/home/cltbld', 18:26:01 INFO - 'LANG': 'en_US.UTF-8', 18:26:01 INFO - 'LANGUAGE': 'en_US:en', 18:26:01 INFO - 'LOGNAME': 'cltbld', 18:26:01 INFO - 'MAIL': '/var/mail/cltbld', 18:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:01 INFO - 'MOZ_NO_REMOTE': '1', 18:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:01 INFO - 'NO_EM_RESTART': '1', 18:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:01 INFO - 'PWD': '/builds/slave/test', 18:26:01 INFO - 'SHELL': '/bin/bash', 18:26:01 INFO - 'SHLVL': '1', 18:26:01 INFO - 'TERM': 'linux', 18:26:01 INFO - 'TMOUT': '86400', 18:26:01 INFO - 'USER': 'cltbld', 18:26:01 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:01 INFO - '_': '/tools/buildbot/bin/python'} 18:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:01 INFO - Running setup.py (path:/tmp/pip-DXwVus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:01 INFO - Running setup.py (path:/tmp/pip-cslMvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:01 INFO - Running setup.py (path:/tmp/pip-BDRsKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:01 INFO - Running setup.py (path:/tmp/pip-UZZPZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:01 INFO - Running setup.py (path:/tmp/pip-HEorsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:01 INFO - Running setup.py (path:/tmp/pip-T_U65a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:01 INFO - Running setup.py (path:/tmp/pip-nSe9PB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:01 INFO - Running setup.py (path:/tmp/pip-byuUFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:01 INFO - Running setup.py (path:/tmp/pip-Vqw153-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:01 INFO - Running setup.py (path:/tmp/pip-scRrvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:01 INFO - Running setup.py (path:/tmp/pip-W2V_S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:02 INFO - Running setup.py (path:/tmp/pip-SnzTHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:02 INFO - Running setup.py (path:/tmp/pip-SxY5Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:02 INFO - Running setup.py (path:/tmp/pip-j02Dtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:02 INFO - Running setup.py (path:/tmp/pip-6vyqJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:02 INFO - Running setup.py (path:/tmp/pip-n0z6E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:02 INFO - Running setup.py (path:/tmp/pip-wqG_6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:02 INFO - Running setup.py install for manifestparser 18:26:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:02 INFO - Running setup.py install for mozcrash 18:26:02 INFO - Running setup.py install for mozdebug 18:26:02 INFO - Running setup.py install for mozdevice 18:26:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:26:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:26:02 INFO - Running setup.py install for mozfile 18:26:03 INFO - Running setup.py install for mozhttpd 18:26:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozinfo 18:26:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozInstall 18:26:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozleak 18:26:03 INFO - Running setup.py install for mozlog 18:26:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for moznetwork 18:26:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozprocess 18:26:03 INFO - Running setup.py install for mozprofile 18:26:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozrunner 18:26:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:26:03 INFO - Running setup.py install for mozscreenshot 18:26:04 INFO - Running setup.py install for moztest 18:26:04 INFO - Running setup.py install for mozversion 18:26:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:26:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:26:04 INFO - Cleaning up... 18:26:04 INFO - Return code: 0 18:26:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:04 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')]} 18:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:04 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')]} 18:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:04 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:26:04 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 18:26:04 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 18:26:04 INFO - Using env: {'DISPLAY': ':0', 18:26:04 INFO - 'HOME': '/home/cltbld', 18:26:04 INFO - 'LANG': 'en_US.UTF-8', 18:26:04 INFO - 'LANGUAGE': 'en_US:en', 18:26:04 INFO - 'LOGNAME': 'cltbld', 18:26:04 INFO - 'MAIL': '/var/mail/cltbld', 18:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:04 INFO - 'MOZ_NO_REMOTE': '1', 18:26:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:04 INFO - 'NO_EM_RESTART': '1', 18:26:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:04 INFO - 'PWD': '/builds/slave/test', 18:26:04 INFO - 'SHELL': '/bin/bash', 18:26:04 INFO - 'SHLVL': '1', 18:26:04 INFO - 'TERM': 'linux', 18:26:04 INFO - 'TMOUT': '86400', 18:26:04 INFO - 'USER': 'cltbld', 18:26:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:04 INFO - '_': '/tools/buildbot/bin/python'} 18:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:04 INFO - Running setup.py (path:/tmp/pip-j5KCW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:04 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)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:04 INFO - Running setup.py (path:/tmp/pip-HEJrGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:04 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)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:04 INFO - Running setup.py (path:/tmp/pip-2PYNFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:04 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)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:04 INFO - Running setup.py (path:/tmp/pip-Inv693-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:04 INFO - Running setup.py (path:/tmp/pip-o3aaoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:04 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)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:04 INFO - Running setup.py (path:/tmp/pip-532pF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:04 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)) 18:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:04 INFO - Running setup.py (path:/tmp/pip-QPf90M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:05 INFO - Running setup.py (path:/tmp/pip-prGmfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:05 INFO - Running setup.py (path:/tmp/pip-TMmyyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:05 INFO - Running setup.py (path:/tmp/pip-5evtWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:05 INFO - Running setup.py (path:/tmp/pip-3iwlf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:05 INFO - Running setup.py (path:/tmp/pip-GZFPPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:05 INFO - Running setup.py (path:/tmp/pip-Wzm2gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:05 INFO - Running setup.py (path:/tmp/pip-BPdPtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:05 INFO - Running setup.py (path:/tmp/pip-DuBOmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:05 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)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:05 INFO - Running setup.py (path:/tmp/pip-8gBNEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:05 INFO - Running setup.py (path:/tmp/pip-xSBf91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:05 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)) 18:26:05 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)) 18:26:05 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)) 18:26:05 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)) 18:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:26:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26:07 INFO - Downloading blessings-1.6.tar.gz 18:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:26:07 INFO - Installing collected packages: blessings 18:26:07 INFO - Running setup.py install for blessings 18:26:07 INFO - Successfully installed blessings 18:26:07 INFO - Cleaning up... 18:26:08 INFO - Return code: 0 18:26:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:08 INFO - Reading from file tmpfile_stdout 18:26:08 INFO - Current package versions: 18:26:08 INFO - argparse == 1.2.1 18:26:08 INFO - blessings == 1.6 18:26:08 INFO - blobuploader == 1.2.4 18:26:08 INFO - docopt == 0.6.1 18:26:08 INFO - functools32 == 3.2.3-2 18:26:08 INFO - jsonschema == 2.5.1 18:26:08 INFO - manifestparser == 1.1 18:26:08 INFO - mozInstall == 1.12 18:26:08 INFO - mozcrash == 1.0 18:26:08 INFO - mozdebug == 0.1 18:26:08 INFO - mozdevice == 0.48 18:26:08 INFO - mozfile == 1.2 18:26:08 INFO - mozhttpd == 0.7 18:26:08 INFO - mozinfo == 0.9 18:26:08 INFO - mozleak == 0.1 18:26:08 INFO - mozlog == 3.4 18:26:08 INFO - moznetwork == 0.27 18:26:08 INFO - mozprocess == 0.23 18:26:08 INFO - mozprofile == 0.28 18:26:08 INFO - mozrunner == 6.13 18:26:08 INFO - mozscreenshot == 0.1 18:26:08 INFO - mozsystemmonitor == 0.3 18:26:08 INFO - moztest == 0.8 18:26:08 INFO - mozversion == 1.4 18:26:08 INFO - psutil == 3.1.1 18:26:08 INFO - requests == 1.2.3 18:26:08 INFO - wsgiref == 0.1.2 18:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:08 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')]} 18:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:08 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')]} 18:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:08 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:26:08 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 18:26:08 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 18:26:08 INFO - Using env: {'DISPLAY': ':0', 18:26:08 INFO - 'HOME': '/home/cltbld', 18:26:08 INFO - 'LANG': 'en_US.UTF-8', 18:26:08 INFO - 'LANGUAGE': 'en_US:en', 18:26:08 INFO - 'LOGNAME': 'cltbld', 18:26:08 INFO - 'MAIL': '/var/mail/cltbld', 18:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:08 INFO - 'MOZ_NO_REMOTE': '1', 18:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:08 INFO - 'NO_EM_RESTART': '1', 18:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:08 INFO - 'PWD': '/builds/slave/test', 18:26:08 INFO - 'SHELL': '/bin/bash', 18:26:08 INFO - 'SHLVL': '1', 18:26:08 INFO - 'TERM': 'linux', 18:26:08 INFO - 'TMOUT': '86400', 18:26:08 INFO - 'USER': 'cltbld', 18:26:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:08 INFO - '_': '/tools/buildbot/bin/python'} 18:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 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)) 18:26:08 INFO - Cleaning up... 18:26:08 INFO - Return code: 0 18:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:26:08 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')]} 18:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:08 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')]} 18:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:08 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 0x7f01cdfb7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf11ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', '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 18:26:08 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 18:26:08 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 18:26:08 INFO - Using env: {'DISPLAY': ':0', 18:26:08 INFO - 'HOME': '/home/cltbld', 18:26:08 INFO - 'LANG': 'en_US.UTF-8', 18:26:08 INFO - 'LANGUAGE': 'en_US:en', 18:26:08 INFO - 'LOGNAME': 'cltbld', 18:26:08 INFO - 'MAIL': '/var/mail/cltbld', 18:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:08 INFO - 'MOZ_NO_REMOTE': '1', 18:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:08 INFO - 'NO_EM_RESTART': '1', 18:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:08 INFO - 'PWD': '/builds/slave/test', 18:26:08 INFO - 'SHELL': '/bin/bash', 18:26:08 INFO - 'SHLVL': '1', 18:26:08 INFO - 'TERM': 'linux', 18:26:08 INFO - 'TMOUT': '86400', 18:26:08 INFO - 'USER': 'cltbld', 18:26:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:08 INFO - '_': '/tools/buildbot/bin/python'} 18:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:26:08 INFO - Cleaning up... 18:26:08 INFO - Return code: 0 18:26:08 INFO - Running post-action listener: _resource_record_post_action 18:26:08 INFO - Running post-action listener: _start_resource_monitoring 18:26:08 INFO - Starting resource monitoring. 18:26:08 INFO - [mozharness: 2017-02-03 02:26:08.752096Z] Finished create-virtualenv step (success) 18:26:08 INFO - [mozharness: 2017-02-03 02:26:08.752548Z] Running install step. 18:26:08 INFO - Running pre-action listener: _resource_record_pre_action 18:26:08 INFO - Running main action method: install 18:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:08 INFO - Reading from file tmpfile_stdout 18:26:08 INFO - Detecting whether we're running mozinstall >=1.0... 18:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:26:09 INFO - Reading from file tmpfile_stdout 18:26:09 INFO - Output received: 18:26:09 INFO - Usage: mozinstall [options] installer 18:26:09 INFO - Options: 18:26:09 INFO - -h, --help show this help message and exit 18:26:09 INFO - -d DEST, --destination=DEST 18:26:09 INFO - Directory to install application into. [default: 18:26:09 INFO - "/builds/slave/test"] 18:26:09 INFO - --app=APP Application being installed. [default: firefox] 18:26:09 INFO - mkdir: /builds/slave/test/build/application 18:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:26:22 INFO - Reading from file tmpfile_stdout 18:26:22 INFO - Output received: 18:26:22 INFO - /builds/slave/test/build/application/firefox/firefox 18:26:22 INFO - Running post-action listener: _resource_record_post_action 18:26:22 INFO - [mozharness: 2017-02-03 02:26:22.806848Z] Finished install step (success) 18:26:22 INFO - [mozharness: 2017-02-03 02:26:22.807181Z] Running run-tests step. 18:26:22 INFO - Running pre-action listener: _resource_record_pre_action 18:26:22 INFO - Running main action method: run_tests 18:26:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:26:22 INFO - Python 2.7.3 18:26:22 INFO - Return code: 0 18:26:22 INFO - grabbing minidump binary from tooltool 18:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:22 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 0xf0ef60>, '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 0xf0fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf101d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:26:22 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 18:26:22 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 18:26:22 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 18:26:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:26:22 INFO - Return code: 0 18:26:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:26:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:26:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:26:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:26:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:26:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 18:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 18:26:22 INFO - Using env: {'DISPLAY': ':0', 18:26:22 INFO - 'HOME': '/home/cltbld', 18:26:22 INFO - 'LANG': 'en_US.UTF-8', 18:26:22 INFO - 'LANGUAGE': 'en_US:en', 18:26:22 INFO - 'LOGNAME': 'cltbld', 18:26:22 INFO - 'MAIL': '/var/mail/cltbld', 18:26:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:26:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:22 INFO - 'MOZ_NO_REMOTE': '1', 18:26:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:26:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:22 INFO - 'NO_EM_RESTART': '1', 18:26:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:22 INFO - 'PWD': '/builds/slave/test', 18:26:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:26:22 INFO - 'SHELL': '/bin/bash', 18:26:22 INFO - 'SHLVL': '1', 18:26:22 INFO - 'TERM': 'linux', 18:26:22 INFO - 'TMOUT': '86400', 18:26:22 INFO - 'USER': 'cltbld', 18:26:22 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778', 18:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:22 INFO - '_': '/tools/buildbot/bin/python'} 18:26:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 18:26:23 INFO - mozversion application_buildid: 20170202163809 18:26:23 INFO - mozversion application_changeset: 9759594e223f25075dde334fe96af323bf33d144 18:26:23 INFO - mozversion application_display_name: Firefox 18:26:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:26:23 INFO - mozversion application_name: Firefox 18:26:23 INFO - mozversion application_remotingname: firefox 18:26:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:26:23 INFO - mozversion application_vendor: Mozilla 18:26:23 INFO - mozversion application_version: 52.0 18:26:23 INFO - mozversion platform_buildid: 20170202163809 18:26:23 INFO - mozversion platform_changeset: 9759594e223f25075dde334fe96af323bf33d144 18:26:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:26:23 INFO - mozversion platform_version: 52.0 18:26:23 INFO - using testdate: 1486088783 18:26:23 INFO - actual date: 1486088783 18:26:23 INFO - starting webserver on 'localhost:37369' 18:26:23 INFO - SUITE-START | Running 2 tests 18:26:23 INFO - TEST-START | dromaeo_css 18:26:23 INFO - operating with platform_type : linux_ 18:26:23 INFO - Initialising browser for dromaeo_css test... 18:26:23 INFO - TEST-INFO | started process 12928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAaRXNU/profile http://localhost:37369/getInfo.html) 18:26:27 INFO - PROCESS | 12928 | __metrics Screen width/height:1600/1200 18:26:27 INFO - PROCESS | 12928 | colorDepth:24 18:26:27 INFO - PROCESS | 12928 | Browser inner width/height: 1024/697 18:26:27 INFO - PROCESS | 12928 | __metrics 18:26:27 INFO - PROCESS | 12928 | 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" 18:26:27 INFO - PROCESS | 12928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:26:28 INFO - PROCESS | 12928 | console.error: PushService: 18:26:28 INFO - PROCESS | 12928 | stateChangeProcessEnqueue: Error transitioning state 18:26:28 INFO - PROCESS | 12928 | UnknownError 18:26:29 INFO - TEST-INFO | 12928: exit 0 18:26:29 INFO - Browser initialized. 18:26:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:26:29 INFO - TEST-INFO | started process 13021 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAaRXNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:40 INFO - PROCESS | 13021 | RSS: Main: 142118912 18:26:40 INFO - PROCESS | 13021 | 18:29:41 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37369/tests/dromaeo/cssquery-ext.html) 18:29:43 INFO - PROCESS | 13021 | RSS: Main: 185147392 18:29:43 INFO - PROCESS | 13021 | 18:32:45 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-ext.html (next: http://localhost:37369/tests/dromaeo/cssquery-jquery.html) 18:32:45 INFO - PROCESS | 13021 | RSS: Main: 223703040 18:32:45 INFO - PROCESS | 13021 | 18:35:47 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37369/tests/dromaeo/cssquery-mootools.html) 18:35:48 INFO - PROCESS | 13021 | RSS: Main: 177049600 18:35:48 INFO - PROCESS | 13021 | 18:38:50 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37369/tests/dromaeo/cssquery-prototype.html) 18:38:50 INFO - PROCESS | 13021 | RSS: Main: 206893056 18:38:50 INFO - PROCESS | 13021 | 18:41:53 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37369/tests/dromaeo/cssquery-yui.html) 18:41:54 INFO - PROCESS | 13021 | RSS: Main: 176136192 18:41:54 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | Cycle 1(1): loaded http://localhost:37369/tests/dromaeo/cssquery-yui.html (next: http://localhost:37369/tests/dromaeo/cssquery-dojo.html) 18:44:56 INFO - PROCESS | 13021 | RSS: Main: 264273920 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | __start_tp_report 18:44:56 INFO - PROCESS | 13021 | _x_x_mozilla_page_load 18:44:56 INFO - PROCESS | 13021 | _x_x_mozilla_page_load_details 18:44:56 INFO - PROCESS | 13021 | |i|pagename|runs| 18:44:56 INFO - PROCESS | 13021 | |0;dojo.html;3169.080966519709;3203.087120170751;3212.662670419606;3234.0459564428493;3254.349130173964;5321.733913304334;5367.302250707403;5378.139497680372;5396.703181325026;5422.484863937924;5451.04606693828;5458.808941687042;5470.425605311445;5475.425080366564;5486.945130548694;4932.704037757728;4933.777979990891;4995.600351971833;5019.84940451787;5031.572316353115;4796.664233503656;4813.542713442217;4891;4899.755012249373;4954.7274899880495;4986.5761410280165;5008.023435430093;5017;5019.447860735316;5029.220470827027;5020.221865610836;5036.395632524121;5040.697558146487;5042.218456139268;5072.492750724953;4820.228763397839;4861.513848615145;4867.099586576494;4930.359053323045;4957.983613359253;5291.4708529147165;5352.384475785296;5353.464653534655;5360.061989151883;5485.6434331768305;5887.7939272125695;5892.292924849003;5898.882022359572;5902.645841249496;5904.527637788967;5627.7467513961565;5693.943060569382;5866.823995280149;5880.500157486619;5932.673702946337;5417.8103766367985;5453.2092846537425;5491.807786727445;5530.5852061095575;5534.501894829405;6029.547783916224;6033.668148251888;6033.909491357633;6035.969820150871;6049.274087109487;5475.123980163155;5502.119660854324;5788.971055144697;5794.072948328331;5795.043817770105;5748.425157484218;5784.334801497797;5804.564657650693;5847.707614619252;5860.120981852756;5821.650700957872;5822.301323841166;5825.359210486843;5828.6211396259105;5829.067349224104;5510.476504732044;5842.357340692521;5846.210761547137;5853.7365818538265;5880.529557635379;4447.288433850568;4451.777411129496;4470.709403888737;4471.105778844244;4520.525344838745;3981.6217459341315;4011.939820902689;4012.7987579432274;4013.9397909031386;4022.255882661717;5453.536449401845;5467.17992301158;5472.863178420572;5475;5499.285092937893;5581;5634.126710359901;5768.192453056576;5773.047447171254;5789.131630255495;5554.250176226238;5560.888782224333;5567.443255674481;5574.247476590608;5592.049351610236;5033.84898453047;5044.596432285409;5071.594272458268;5091.159958606789;5100.260462232955;5732.799352022659;5748.942510574924;5804.477597016289;5842.152887831326;5952.85117872048;5740.799072032459;5744.569157313302;5745.74144163522;5765.971170144123;5775.653460792198;5911.793087241926;5926.4666180044005;5941.1385349125;5950.642961422329;6001.309849367379;5373.650712703662;5375.5162035417;5650.039493286234;5701.287339082614;5704.030314846403;5741.827745167651;5841.269841269841;5856.238688970407;5880.441358070976;5883.264591926009;5640.774369025193;5678.14827775837;5694.117411801178;5703.401142879938;5706.857328566819;5464.071107911744;5719.2279042328755;5720.227769251098;5720.914186287292;5726.398728133486;5457.727113644222;5557.36090349615;5562.471565201299;5604.719764011865;5698.914516282342;5717.709919852222;5737.569682273763;5748.051571490657;5763.94236057634;5764.481196692318;5433.293671822637;5491.643043202179;5492.368377636463;5618.943810562006;5619.185218143428 18:44:56 INFO - PROCESS | 13021 | |1;ext.html;607.3258682862022;623.9656818874962;634.5241069198103;655.5312951239863;667.3726696904909;35644.10889727755;46381.53618463814;46531.30203046957;46866.765666171705;47074.764626176824;47993.52006479934;47999.28001079986;48448;48487;48539;8339.12439193885;8671.393002489844;8783.47299162049;8823.823523529525;8824.911750882473;8457.196566326189;8605.913940860573;8609.655613775441;10613.946930265376;10863.29388589743;10694;10725.731856703605;10729.248952573324;10731.678049658483;10731.83902241467;10481.371117732922;10540.420276884768;10542.051215390651;10550;10561;10757.085647719954;10830.566777328897;10831.133509319314;10832.67501974942;10834.187435942256;44512.55487445149;44711.32933006008;44716;44723.77638111789;44848.3272750909;3169.952450713241;3309.6855798699085;3315.469524876032;3381.695647391722;3549.893503194908;33502.6649733502;33539;33799.662003380145;33829;34988.30023399543;3476.2699833035094;3560.305740380627;3562.358775420423;3581.4448760442174;3610.079429745398;337729.11354432424;338291;338670;338678;339334.30332848424;33851.661483385345;34849.30301393958;64848.378790530616;65198.67400662966;65334.69330613361;41545;41650.37524437136;41656;41685.37471937924;41711.7914410426;17144.571385715455;17267.82732172662;17318;17324.65350692979;17326.480205593853;12902.870971290167;12910.419031143621;12917.483300667867;12920.160189587647;12934.741305173844;8986.820263594691;9130.132637399436;9242.815143697222;9598.32811703188;9621.51892405377;3601.6758491735345;3629.4374371972385;3648.1062139775395;3673.522442082512;3702.851885924533;954.1698722111807;1034.4879284753945;1047.9685609431576;1153.8595210787805;1175.5944199251244;31852.203694907814;32553.186170345933;33433.83283083569;33793.32413351719;34142.48786268208;1111.1388673777888;1113.8329250612474;1177.2465622001926;1191.6365508519984;1240.1257113734976;15103.697926041637;17761.822381776274;18269.269229230948;18914.716279255823;19318.613627727365;478987;486084;487177;489056;489071;60698;61233;61247;61261.38738612557;61340;1311;1375.1955106262749;1376.1399125546334;1378.4486205517674;1417.4471955937192;31918.840405797822;33653.49519757255;34698.65301346954;34981.47527787136;35176.47235291524;53001;53900.46099539111;53932.460675394315;54035;54086.459135409714;358707;362079;363788;366003;368207.27170912863;27849.303767405978;28588.285292867844;30925.845370773;31607.84196079005;31667.52498712567;49437.75281123715;49498;50192.24711629401;50385;50451.49548504468;41533;42047.57952420437;42279.788601056796;42327.3650895243;43758.56241437545;42077.36883946682;42804.78597606992;43000.56999429966;44072.779636101615;44207.55792442035;42916.570834291255;46547.30179047249;46629.53370466252;47787.522124779694;47919.28121078257;35935.101622459646;38697.80651096727;39206;39243.80378098091;39435.81692549228;29067.965242432827;29421.26446838846;29569.112926612237;29820.552691709207;29941.70058299389 18:44:56 INFO - PROCESS | 13021 | |2;jquery.html;402.46673158065573;413.5512968429254;422.4887885658353;428.59497774603005;434.600167845582;18883.811161888378;20377;20647.38057858263;20863.47841303968;21274.893625531888;17598.120093995312;17815.287388504476;18340.72488912664;18723.25106995722;19446.22215111394;5182.6631268967585;5593;5637.520810731093;5642.407547207546;5644.4637759413;4240.194363071022;5575.944240557604;5659.801906933259;5661.7735290588535;5666.376698563155;4513.14250292445;4538.251188553884;6342.682865856688;6426.742930282783;6446.387593178641;6263.6241825490615;6397.200349956255;6439.484841212715;6440.355964403546;6441.935580644181;5766.135079738031;5777.682227477487;5789.334226563957;5790.536757059467;5791;23639.881800590887;23654.290371288716;23762;24258.87870560636;24462.143824966042;2410.8418663752836;2486.76375744304;2680.557707978181;2902.492063888811;3132.7938743583654;14852.925735371362;14921;15034.473793417177;15349.846501534954;15466.30401631918;1915.348781414326;1967.931122410723;2019.3538067818304;2034.7049677796786;2055.075216152737;62907;73946.63026684833;74432;82281.58859205665;86885.56557217236;10583.576656933636;10708.30396024256;10886.346819190716;10965.56137754497;10968.122550195965;15099.320530575931;15297.158656274012;15408.537743867702;15497.380104795908;15507.844921550866;8074.798130046678;8084.878726819103;8105.878411823827;8132.390070744599;8611.440256383315;7590.658420371096;7606.315431611071;7619.352355049772;7808.102068262109;7868.960655196687;6317.6209427434505;6439.871202575922;6467.547271690936;6475.546711730133;6536.934630653727;1927.575933294673;1943.3878328326532;1956.814102660245;1968.6948522978962;1971.8521110916738;970.3789574672215;1017.2828156149897;1051.6109039655223;1117.8323251512338;1137.4995002199005;19198.13608387626;19506.414807555797;20508.79491205069;20723;21094.578108437745;1047.324475713176;1065.8934106589434;1086.9456527173609;1109.7003808971533;1143.9084873210122;10749.78500429987;11700.70748231301;11707.356095414663;11777;11784.116191285688;477188;484457;487439;496206;496633;81751;81760;81785.59107204546;81910.59044704738;81939;1035.761774791802;1081.9026287634151;1099.4667586220676;1106.4080716816466;1114.7993361194901;17388.391406301223;17540.58332126625;17817.910910445364;17830.46508604744;17999.19003644813;40185.1962960745;40561.391579126925;41475.7926210367;42135.7893210532;42878.35682464703;51369.229461558854;51882.74058629683;52724.472755271956;53044.20433693421;53763;8677.956610216908;8794.60424280896;8931.19619234272;8931.73204803857;9103.681371151977;9308;12926.870731292944;13085.869141308465;13697.726045478836;14030.29848507591;11326.603568875049;11340.773184536098;11641.41792910368;11932.105092117952;12360;8418.284445821928;11067.944660276647;11611.593594224161;11849.526018959144;11861.347625880659;11454;11493.712657183638;11620.76758464809;11779.29324240514;12181.685479758817;8779.679459343486;8905.821883562163;8979.910200897908;9048.276137909083;9165.541722913695;10458.424786636504;10734.570617175226;11207.607733729606;11245;11438.771224575295 18:44:56 INFO - PROCESS | 13021 | |3;mootools.html;1318.82195903553;1438.7554115800317;1485.8217013958324;1609.4769200009991;1793.4978206102296;7427.591482468469;7847.21527847215;8494.362922780792;8738.519381434035;8744.825103497926;8726.38915275931;8776.034636190014;8815.603297851596;8816.823663526726;8820.691275805348;2211.8230541556677;2248.5952528544854;2253.1325439705747;2254.120892851789;2257.0858802185057;2378.4172877645;2437.8415402998835;2443.621238707994;2459.32368598635;2461.396957745355;2358.2335740884196;2389.928302150938;2402.7236867760257;2440.719317278518;2441.3042282949364;2280.441291883491;2300.654901764732;2332.661764044204;2335.5912715274844;2361.97638023621;2328.6623439601162;2344.800691941187;2344.906203751848;2349.3774149850306;2350.670906073151;8252.092269850311;8546.786330341729;8553.059163492011;8563.957180214122;8737.388382813206;5132.255822905658;5222.216667499867;5259.263703081571;5268.4994925482015;5369.973150134263;2996.715312045374;3244.902652920416;3281.4913688378338;3301.042697617688;3303.2072302647193;979.7893452907659;983.7343917142404;998.3510718033264;1077.7628921637227;1084.2572837606265;11768.705782355424;11971.102167337485;12127.39363031845;12444.377781111;12461.127721059529;4493.505714371417;4507.706999045117;4597.241455159928;4624.930626040612;4686.8125274988615;9757.073078057572;9783.72811534496;9787.657431989846;9788.168005719452;9789.265805064648;5277.630565860353;5279.815206467755;5280.735963201824;5281.102212623863;5281.471852814765;4189.72766770159;4232.3016702243785;4873.19592267279;4925.162722337212;4928;3880.8447662093763;3991.560928297885;4007.779572123503;4024.4969378827645;4095.6109169629435;1225.96935076622;1227.2943057741834;1237.1216436330126;1242.819791130299;1247.5009996001672;953.2469349214182;988.8022395520925;997.4563862694848;998.6604554451376;999.1906555689915;5857.690404921291;5894.056950887837;5928.407159284037;5944;5993.880122397528;441.3556207936385;448.39914514550657;449.3686370649243;1009.8687170667766;1020.9795804083876;4023.5775243599587;4131.938020929689;4135.503739551213;4147.025449019478;4174.3321068628875;10587.100096491731;11121.110311175087;11122.888771112348;11123.777524449466;11127.387993660426;10961;10994.450277486094;11007.284526505751;11019.944900275448;11042.503087361089;8127.715529956423;8305.626246818907;8336.708215212439;8341.66633334672;8342.706880433421;5792.160136780059;6056.273247210363;6061.454469097803;6087.239095113111;6102.237220347457;9913.45475998827;9966.451845148296;10057.798844023226;10125.898741012496;10153.949230254097;10111.24165687591;10191.03185197405;10226.028527289896;10232.23258255619;10233.181345492492;6687.297833727389;7517.586532740533;7573.318401343906;7687.423443241891;7860.606969651609;8487.36344774132;8661.91338086611;8665.306775457822;8668.78328041804;8670.869936951078;7641.1212710539;7651.693932242648;7797.727079552188;7844.058712954482;7927.801804954922;7309.634518274172;7327.340539351484;7345.22875098107;7362.926370736438;7406;6576.868462630816;6669.299723529154;6670.099536562696;6671.166104236971;6712.966435167793;4811.302361157683;4827.251775974589;4892.926606100982;5005.249212618136;5123.692578445305;4659.60393366554;4663.813447462064;4764.2615394613895;4833.444153922203;4839.733814640229 18:44:56 INFO - PROCESS | 13021 | |4;prototype.html;338.9525466434698;342.9365567370037;352.18820618474405;354.4771462093412;355.9394902866512;5413.188021796727;5439.728013599324;5453.400125986138;5461.726913654321;5469.78120875165;5092.108880945829;5159.458256883029;5221.138512145491;5241.764120614572;5363.973180134104;4183.3515805050265;4189.748615083089;4197.656749840053;4199.160167966404;4203.579642035803;4231.767252801094;4235.512916014667;4239.872803815875;4243.215005224028;4244.617984381405;4121.649659778923;4160.39674247234;4167.5205302117765;4199.3281075028;4210.073783767566;4179.059711564904;4187.466582717473;4188.81150348235;4193.720915120872;4195.8741237762915;3720.2001569662384;3722.994791406305;3742;3742.3450896093077;3752.19327844512;5036.294918711384;5158;5196.714180720059;5200.595839123415;5215.191645294985;4822.831200907952;4838.927416088762;4858.46556878743;4891.608671306322;4893.437254715717;5572.442755724435;5596.496315331639;5628.493435590775;5639.746211420496;5654.9151762723595;3878.4570160177313;4066.755994640331;4158.7296825706235;4169.332906734908;4182.330827067654;5797.449242321973;5805.477507024388;5807.622504537191;5835.416458354173;5842.269716285465;4159.750414975111;4162.501499460192;4171.728837625545;4172.395002724588;4180.306975674856;4117.5882411758585;4212.347086201644;4221.91556168881;4223.049813791872;4234.470691163605;4754.597393768847;4771.451283102488;4776;4800.183968725325;4808.927866081942;5606.775728970877;5626.2685850839125;5636.802711905064;5656.547476201894;5679.460451257124;1368.0423703407655;1372.3549931532164;1386.6949271160329;1394.9607542380788;1402.5441731437325;935.9906400935904;956.9665061722945;967.9642782223094;974.2985050763439;979.6179489998906;5051;5283;5290.232916227125;5298.735063246822;5322;5492.286002819687;5503.642263252956;5561.499465048084;5574.581906356958;5659.745311460912;5237.973810130925;5254.266092189569;5265.631405801557;5268.28878101459;5282.894342113136;2822.911045826074;2950.660674022472;2956.2461572298926;2961.466935951549;2969.7475714564503;5797.594168408255;5802.65184088956;5819.555786569651;5821.708914554255;5832.329282132524;5690.748035432198;5702.344230413555;5714.428557144252;5729.455701708414;5732.570057245724;5647.265855438766;5766.711664416763;5772.624779389326;5830.3586605473365;5842.941570584239;5550.75021624036;5553.222968649993;5562.16567514876;5593.4126916674795;5625.284288291947;5287.339082614673;5518.613697041169;5527.336719593674;5544.362398324244;5591.552675786009;5742.540596752333;5749.223854779719;5771.59598828078;5793.94206057934;5805.603721822781;3970.900727481836;3971.52341718984;3998.100427403811;4009.4787677601726;4019.517657881073;5389.326334208224;5525.585581081355;5553.750081246434;5745.741441635053;5763.250777398911;5617.9719101404235;5630.971845140748;5639.351474580311;5648.011597970257;5648.774049037993;5489.862753431196;5571.749271282721;5637.43625637433;5661.405552417102;5667.943320566907;5536.889262214814;5548.500634942713;5571.9442805571425;5572.721363931868;5578.804741834017;5609.943900561106;5627.577931655224;5640.294963129609;5643.689597072036;5690;5305.750044627823;5446.945530544803;5469.562435005269;5493.533049690661;5502.394736578893 18:44:56 INFO - PROCESS | 13021 | |5;yui.html;642.6336987916887;773.2615352338518;774.9573773442462;808.9474184178032;839.4627438439396;13903.16581005139;14535.200563968965;14877.776833347509;15381.69236615267;15558;13891.583252502416;14088.36602352894;14167.78748318776;14240.14559126453;14250.572482825506;842.2125312832491;860.565414465694;860.8751730999002;862.094800459515;862.6937437209795;719.8380364418016;735.6101266328827;827.2430725885807;843;845.0746432656232;912.5254867468946;915.50562696144;915.8626206069076;916.5921165081542;916.724982505249;906.9909300906972;907.0838453162321;908.5366463103799;910.6084383715;911.007002367416;668.2382084423713;668.3183153183774;668.9598624082522;669.1434963247052;669.357416879796;13679.452821887213;14053.297335133202;14124.576262712135;14154.292285385689;15172.165530895796;13809.447622095104;14176.149431034068;14269.500567480089;14354.210518421483;14462.421503139862;11386.373749443777;11542.769144617145;11551.306921584648;11705.122115841346;11793;3555.9466608000903;3559.1280136366754;3579.19468119671;3585.4621806728937;3685.3919103347916;66241.33758662448;66478.66760666166;66673.66663166702;66986.66506667485;68427;10747.570097196181;10762.515686794113;10815.459227038617;10851.655193711826;11183.329000259848;22096.33710988673;22103.889480552494;22159.22442714497;22160.113595456325;22191.889040554695;7552.131504877009;7710.34462070719;7758.185390534026;7921.524708517507;8234.794130146794;4417.8232870684815;4472.597466228055;4503.527129651405;4604.8388306409115;4636.026434448803;3180.3957248122783;3260.6413294537583;3293.86824527021;3296.950545741816;3306.7189288910713;547.5482726750447;549.5246611680867;555.630505713704;559.4098226371136;561.738791461964;718.4862823081418;724.5435375713267;725.5320318394606;731.4696844787486;733.5342057793233;8699.54265261481;8860.601272942733;9017;9041.728748137566;9188.678396256098;474.9738764367993;567.9176519404663;568.6275489554348;582.1994757208838;618.2797041446664;4386.188555117314;4474.910501790011;4576.473705523841;4595.540445955377;4657.161710892072;72194.27805721981;73215;74909.62545187348;93630.53184734033;97128.51435742776;70672.64663676648;71901;72394.63802680952;72994;74366.6281668599;53038;65403;65752.67123664351;67033.66483167553;67567.3243267561;9581;9896;10212.846807298045;10216.846747298945;10244.846327304947;45619;46197.53802461932;46251.306230407245;46448;47520.2871956914;58225.417745823695;61434.38565614287;62968;64165.35834641594;64995;12386;12528.123031387715;12631.93684031574;12663.81004284918;12780.360981951051;14209.360578774187;14238.860891128892;14363.85636143667;14410.783838242225;15238.923805380902;13176.03631466047;13398.06213565041;13630.318484075558;13842.169469831842;13860.930695346458;13178.616245294106;13537.1200871943;13730.794038089236;13741.381637826122;13851.238181900093;8241.316453167625;11702.239354441934;12390.504379824706;12487.438065286902;12880.098393112392;10111.64609238673;10327.431991240554;10821.56713731442;10941.945290273497;11170.218084733991;9726.513674316115;10112.949435252776;10438.21713371485;11157.88842111601;11260.155488338243 18:44:56 INFO - PROCESS | 13021 | __end_tp_report 18:44:56 INFO - PROCESS | 13021 | __start_cc_report 18:44:56 INFO - PROCESS | 13021 | _x_x_mozilla_cycle_collect,1482 18:44:56 INFO - PROCESS | 13021 | __end_cc_report 18:44:56 INFO - PROCESS | 13021 | __startTimestamp1486089896537__endTimestamp 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | ------- Summary: start ------- 18:44:56 INFO - PROCESS | 13021 | Number of tests: 6 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#0] dojo.html Cycles:165 Average:5392.88 Median:5560.89 stddev:591.66 (10.6%) stddev-sans-first:567.18 18:44:56 INFO - PROCESS | 13021 | Values: 3169.1 3203.1 3212.7 3234.0 3254.3 5321.7 5367.3 5378.1 5396.7 5422.5 5451.0 5458.8 5470.4 5475.4 5486.9 4932.7 4933.8 4995.6 5019.8 5031.6 4796.7 4813.5 4891.0 4899.8 4954.7 4986.6 5008.0 5017.0 5019.4 5029.2 5020.2 5036.4 5040.7 5042.2 5072.5 4820.2 4861.5 4867.1 4930.4 4958.0 5291.5 5352.4 5353.5 5360.1 5485.6 5887.8 5892.3 5898.9 5902.6 5904.5 5627.7 5693.9 5866.8 5880.5 5932.7 5417.8 5453.2 5491.8 5530.6 5534.5 6029.5 6033.7 6033.9 6036.0 6049.3 5475.1 5502.1 5789.0 5794.1 5795.0 5748.4 5784.3 5804.6 5847.7 5860.1 5821.7 5822.3 5825.4 5828.6 5829.1 5510.5 5842.4 5846.2 5853.7 5880.5 4447.3 4451.8 4470.7 4471.1 4520.5 3981.6 4011.9 4012.8 4013.9 4022.3 5453.5 5467.2 5472.9 5475.0 5499.3 5581.0 5634.1 5768.2 5773.0 5789.1 5554.3 5560.9 5567.4 5574.2 5592.0 5033.8 5044.6 5071.6 5091.2 5100.3 5732.8 5748.9 5804.5 5842.2 5952.9 5740.8 5744.6 5745.7 5766.0 5775.7 5911.8 5926.5 5941.1 5950.6 6001.3 5373.7 5375.5 5650.0 5701.3 5704.0 5741.8 5841.3 5856.2 5880.4 5883.3 5640.8 5678.1 5694.1 5703.4 5706.9 5464.1 5719.2 5720.2 5720.9 5726.4 5457.7 5557.4 5562.5 5604.7 5698.9 5717.7 5737.6 5748.1 5763.9 5764.5 5433.3 5491.6 5492.4 5618.9 5619.2 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#1] ext.html Cycles:180 Average:56639.00 Median:3605.88 stddev:105951.17 (2938.3%) stddev-sans-first:106164.87 18:44:56 INFO - PROCESS | 13021 | Values: 607.3 624.0 634.5 655.5 667.4 35644.1 46381.5 46531.3 46866.8 47074.8 47993.5 47999.3 48448.0 48487.0 48539.0 8339.1 8671.4 8783.5 8823.8 8824.9 8457.2 8605.9 8609.7 10613.9 10863.3 10694.0 10725.7 10729.2 10731.7 10731.8 10481.4 10540.4 10542.1 10550.0 10561.0 10757.1 10830.6 10831.1 10832.7 10834.2 44512.6 44711.3 44716.0 44723.8 44848.3 3170.0 3309.7 3315.5 3381.7 3549.9 33502.7 33539.0 33799.7 33829.0 34988.3 3476.3 3560.3 3562.4 3581.4 3610.1 337729.1 338291.0 338670.0 338678.0 339334.3 33851.7 34849.3 64848.4 65198.7 65334.7 41545.0 41650.4 41656.0 41685.4 41711.8 17144.6 17267.8 17318.0 17324.7 17326.5 12902.9 12910.4 12917.5 12920.2 12934.7 8986.8 9130.1 9242.8 9598.3 9621.5 3601.7 3629.4 3648.1 3673.5 3702.9 954.2 1034.5 1048.0 1153.9 1175.6 31852.2 32553.2 33433.8 33793.3 34142.5 1111.1 1113.8 1177.2 1191.6 1240.1 15103.7 17761.8 18269.3 18914.7 19318.6 478987.0 486084.0 487177.0 489056.0 489071.0 60698.0 61233.0 61247.0 61261.4 61340.0 1311.0 1375.2 1376.1 1378.4 1417.4 31918.8 33653.5 34698.7 34981.5 35176.5 53001.0 53900.5 53932.5 54035.0 54086.5 358707.0 362079.0 363788.0 366003.0 368207.3 27849.3 28588.3 30925.8 31607.8 31667.5 49437.8 49498.0 50192.2 50385.0 50451.5 41533.0 42047.6 42279.8 42327.4 43758.6 42077.4 42804.8 43000.6 44072.8 44207.6 42916.6 46547.3 46629.5 47787.5 47919.3 35935.1 38697.8 39206.0 39243.8 39435.8 29068.0 29421.3 29569.1 29820.6 29941.7 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#2] jquery.html Cycles:180 Average:28642.36 Median:13334.86 stddev:80159.74 (601.1%) stddev-sans-first:80356.57 18:44:56 INFO - PROCESS | 13021 | Values: 402.5 413.6 422.5 428.6 434.6 18883.8 20377.0 20647.4 20863.5 21274.9 17598.1 17815.3 18340.7 18723.3 19446.2 5182.7 5593.0 5637.5 5642.4 5644.5 4240.2 5575.9 5659.8 5661.8 5666.4 4513.1 4538.3 6342.7 6426.7 6446.4 6263.6 6397.2 6439.5 6440.4 6441.9 5766.1 5777.7 5789.3 5790.5 5791.0 23639.9 23654.3 23762.0 24258.9 24462.1 2410.8 2486.8 2680.6 2902.5 3132.8 14852.9 14921.0 15034.5 15349.8 15466.3 1915.3 1967.9 2019.4 2034.7 2055.1 62907.0 73946.6 74432.0 82281.6 86885.6 10583.6 10708.3 10886.3 10965.6 10968.1 15099.3 15297.2 15408.5 15497.4 15507.8 8074.8 8084.9 8105.9 8132.4 8611.4 7590.7 7606.3 7619.4 7808.1 7869.0 6317.6 6439.9 6467.5 6475.5 6536.9 1927.6 1943.4 1956.8 1968.7 1971.9 970.4 1017.3 1051.6 1117.8 1137.5 19198.1 19506.4 20508.8 20723.0 21094.6 1047.3 1065.9 1086.9 1109.7 1143.9 10749.8 11700.7 11707.4 11777.0 11784.1 477188.0 484457.0 487439.0 496206.0 496633.0 81751.0 81760.0 81785.6 81910.6 81939.0 1035.8 1081.9 1099.5 1106.4 1114.8 17388.4 17540.6 17817.9 17830.5 17999.2 40185.2 40561.4 41475.8 42135.8 42878.4 51369.2 51882.7 52724.5 53044.2 53763.0 8678.0 8794.6 8931.2 8931.7 9103.7 9308.0 12926.9 13085.9 13697.7 14030.3 11326.6 11340.8 11641.4 11932.1 12360.0 8418.3 11067.9 11611.6 11849.5 11861.3 11454.0 11493.7 11620.8 11779.3 12181.7 8779.7 8905.8 8979.9 9048.3 9165.5 10458.4 10734.6 11207.6 11245.0 11438.8 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#3] mootools.html Cycles:180 Average:5718.47 Median:4819.28 stddev:3274.42 (67.9%) stddev-sans-first:3266.91 18:44:56 INFO - PROCESS | 13021 | Values: 1318.8 1438.8 1485.8 1609.5 1793.5 7427.6 7847.2 8494.4 8738.5 8744.8 8726.4 8776.0 8815.6 8816.8 8820.7 2211.8 2248.6 2253.1 2254.1 2257.1 2378.4 2437.8 2443.6 2459.3 2461.4 2358.2 2389.9 2402.7 2440.7 2441.3 2280.4 2300.7 2332.7 2335.6 2362.0 2328.7 2344.8 2344.9 2349.4 2350.7 8252.1 8546.8 8553.1 8564.0 8737.4 5132.3 5222.2 5259.3 5268.5 5370.0 2996.7 3244.9 3281.5 3301.0 3303.2 979.8 983.7 998.4 1077.8 1084.3 11768.7 11971.1 12127.4 12444.4 12461.1 4493.5 4507.7 4597.2 4624.9 4686.8 9757.1 9783.7 9787.7 9788.2 9789.3 5277.6 5279.8 5280.7 5281.1 5281.5 4189.7 4232.3 4873.2 4925.2 4928.0 3880.8 3991.6 4007.8 4024.5 4095.6 1226.0 1227.3 1237.1 1242.8 1247.5 953.2 988.8 997.5 998.7 999.2 5857.7 5894.1 5928.4 5944.0 5993.9 441.4 448.4 449.4 1009.9 1021.0 4023.6 4131.9 4135.5 4147.0 4174.3 10587.1 11121.1 11122.9 11123.8 11127.4 10961.0 10994.5 11007.3 11019.9 11042.5 8127.7 8305.6 8336.7 8341.7 8342.7 5792.2 6056.3 6061.5 6087.2 6102.2 9913.5 9966.5 10057.8 10125.9 10153.9 10111.2 10191.0 10226.0 10232.2 10233.2 6687.3 7517.6 7573.3 7687.4 7860.6 8487.4 8661.9 8665.3 8668.8 8670.9 7641.1 7651.7 7797.7 7844.1 7927.8 7309.6 7327.3 7345.2 7362.9 7406.0 6576.9 6669.3 6670.1 6671.2 6713.0 4811.3 4827.3 4892.9 5005.2 5123.7 4659.6 4663.8 4764.3 4833.4 4839.7 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#4] prototype.html Cycles:180 Average:4690.55 Median:5342.99 stddev:1362.91 (25.5%) stddev-sans-first:1327.02 18:44:56 INFO - PROCESS | 13021 | Values: 339.0 342.9 352.2 354.5 355.9 5413.2 5439.7 5453.4 5461.7 5469.8 5092.1 5159.5 5221.1 5241.8 5364.0 4183.4 4189.7 4197.7 4199.2 4203.6 4231.8 4235.5 4239.9 4243.2 4244.6 4121.6 4160.4 4167.5 4199.3 4210.1 4179.1 4187.5 4188.8 4193.7 4195.9 3720.2 3723.0 3742.0 3742.3 3752.2 5036.3 5158.0 5196.7 5200.6 5215.2 4822.8 4838.9 4858.5 4891.6 4893.4 5572.4 5596.5 5628.5 5639.7 5654.9 3878.5 4066.8 4158.7 4169.3 4182.3 5797.4 5805.5 5807.6 5835.4 5842.3 4159.8 4162.5 4171.7 4172.4 4180.3 4117.6 4212.3 4221.9 4223.0 4234.5 4754.6 4771.5 4776.0 4800.2 4808.9 5606.8 5626.3 5636.8 5656.5 5679.5 1368.0 1372.4 1386.7 1395.0 1402.5 936.0 957.0 968.0 974.3 979.6 5051.0 5283.0 5290.2 5298.7 5322.0 5492.3 5503.6 5561.5 5574.6 5659.7 5238.0 5254.3 5265.6 5268.3 5282.9 2822.9 2950.7 2956.2 2961.5 2969.7 5797.6 5802.7 5819.6 5821.7 5832.3 5690.7 5702.3 5714.4 5729.5 5732.6 5647.3 5766.7 5772.6 5830.4 5842.9 5550.8 5553.2 5562.2 5593.4 5625.3 5287.3 5518.6 5527.3 5544.4 5591.6 5742.5 5749.2 5771.6 5793.9 5805.6 3970.9 3971.5 3998.1 4009.5 4019.5 5389.3 5525.6 5553.8 5745.7 5763.3 5618.0 5631.0 5639.4 5648.0 5648.8 5489.9 5571.7 5637.4 5661.4 5667.9 5536.9 5548.5 5571.9 5572.7 5578.8 5609.9 5627.6 5640.3 5643.7 5690.0 5305.8 5446.9 5469.6 5493.5 5502.4 18:44:56 INFO - PROCESS | 13021 | 18:44:56 INFO - PROCESS | 13021 | [#5] yui.html Cycles:180 Average:17576.39 Median:46224.42 stddev:22803.86 (49.3%) stddev-sans-first:22832.38 18:44:56 INFO - PROCESS | 13021 | Values: 642.6 773.3 775.0 808.9 839.5 13903.2 14535.2 14877.8 15381.7 15558.0 13891.6 14088.4 14167.8 14240.1 14250.6 842.2 860.6 860.9 862.1 862.7 719.8 735.6 827.2 843.0 845.1 912.5 915.5 915.9 916.6 916.7 907.0 907.1 908.5 910.6 911.0 668.2 668.3 669.0 669.1 669.4 13679.5 14053.3 14124.6 14154.3 15172.2 13809.4 14176.1 14269.5 14354.2 14462.4 11386.4 11542.8 11551.3 11705.1 11793.0 3555.9 3559.1 3579.2 3585.5 3685.4 66241.3 66478.7 66673.7 66986.7 68427.0 10747.6 10762.5 10815.5 10851.7 11183.3 22096.3 22103.9 22159.2 22160.1 22191.9 7552.1 7710.3 7758.2 7921.5 8234.8 4417.8 4472.6 4503.5 4604.8 4636.0 3180.4 3260.6 3293.9 3297.0 3306.7 547.5 549.5 555.6 559.4 561.7 718.5 724.5 725.5 731.5 733.5 8699.5 8860.6 9017.0 9041.7 9188.7 475.0 567.9 568.6 582.2 618.3 4386.2 4474.9 4576.5 4595.5 4657.2 72194.3 73215.0 74909.6 93630.5 97128.5 70672.6 71901.0 72394.6 72994.0 74366.6 53038.0 65403.0 65752.7 67033.7 67567.3 9581.0 9896.0 10212.8 10216.8 10244.8 45619.0 46197.5 46251.3 46448.0 47520.3 58225.4 61434.4 62968.0 64165.4 64995.0 12386.0 12528.1 12631.9 12663.8 12780.4 14209.4 14238.9 14363.9 14410.8 15238.9 13176.0 13398.1 13630.3 13842.2 13860.9 13178.6 13537.1 13730.8 13741.4 13851.2 8241.3 11702.2 12390.5 12487.4 12880.1 10111.6 10327.4 10821.6 10941.9 11170.2 9726.5 10112.9 10438.2 11157.9 11260.2 18:44:56 INFO - PROCESS | 13021 | -------- Summary: end -------- 18:44:56 INFO - PROCESS | 13021 | 18:44:57 INFO - TEST-INFO | 13021: exit 0 18:44:57 INFO - TEST-OK | dromaeo_css | took 1114251ms 18:44:57 INFO - TEST-START | kraken 18:44:57 INFO - operating with platform_type : linux_ 18:44:57 INFO - Initialising browser for kraken test... 18:44:57 INFO - TEST-INFO | started process 13327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRQ6P3Q/profile http://localhost:37369/getInfo.html) 18:45:01 INFO - PROCESS | 13327 | __metrics Screen width/height:1600/1200 18:45:01 INFO - PROCESS | 13327 | colorDepth:24 18:45:01 INFO - PROCESS | 13327 | Browser inner width/height: 1024/697 18:45:01 INFO - PROCESS | 13327 | __metrics 18:45:02 INFO - PROCESS | 13327 | 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" 18:45:02 INFO - PROCESS | 13327 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:45:03 INFO - PROCESS | 13327 | console.error: PushService: 18:45:03 INFO - PROCESS | 13327 | stateChangeProcessEnqueue: Error transitioning state 18:45:03 INFO - PROCESS | 13327 | UnknownError 18:45:03 INFO - TEST-INFO | 13327: exit 0 18:45:03 INFO - Browser initialized. 18:45:03 INFO - Running cycle 1/1 for kraken test... 18:45:03 INFO - TEST-INFO | started process 13419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRQ6P3Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:15 INFO - PROCESS | 13419 | RSS: Main: 141893632 18:45:15 INFO - PROCESS | 13419 | 18:45:17 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37369/tests/kraken/driver.html?testName=audio-beat-detection) 18:45:18 INFO - PROCESS | 13419 | RSS: Main: 183111680 18:45:18 INFO - PROCESS | 13419 | 18:45:21 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37369/tests/kraken/driver.html?testName=audio-dft) 18:45:21 INFO - PROCESS | 13419 | RSS: Main: 172826624 18:45:21 INFO - PROCESS | 13419 | 18:45:24 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37369/tests/kraken/driver.html?testName=audio-fft) 18:45:25 INFO - PROCESS | 13419 | RSS: Main: 264208384 18:45:25 INFO - PROCESS | 13419 | 18:45:27 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37369/tests/kraken/driver.html?testName=audio-oscillator) 18:45:27 INFO - PROCESS | 13419 | RSS: Main: 170074112 18:45:27 INFO - PROCESS | 13419 | 18:45:30 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37369/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:45:30 INFO - PROCESS | 13419 | RSS: Main: 173342720 18:45:30 INFO - PROCESS | 13419 | 18:45:33 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37369/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:34 INFO - PROCESS | 13419 | RSS: Main: 212320256 18:45:34 INFO - PROCESS | 13419 | 18:45:37 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37369/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:38 INFO - PROCESS | 13419 | RSS: Main: 210755584 18:45:38 INFO - PROCESS | 13419 | 18:45:40 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37369/tests/kraken/driver.html?testName=json-parse-financial) 18:45:41 INFO - PROCESS | 13419 | RSS: Main: 209661952 18:45:41 INFO - PROCESS | 13419 | 18:45:42 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37369/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:43 INFO - PROCESS | 13419 | RSS: Main: 171143168 18:45:43 INFO - PROCESS | 13419 | 18:45:45 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:45 INFO - PROCESS | 13419 | RSS: Main: 179335168 18:45:45 INFO - PROCESS | 13419 | 18:45:47 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:45:48 INFO - PROCESS | 13419 | RSS: Main: 188162048 18:45:48 INFO - PROCESS | 13419 | 18:45:50 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:45:51 INFO - PROCESS | 13419 | RSS: Main: 195940352 18:45:51 INFO - PROCESS | 13419 | 18:45:54 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:45:54 INFO - PROCESS | 13419 | RSS: Main: 192581632 18:45:54 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | Cycle 1(1): loaded http://localhost:37369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37369/tests/kraken/driver.html?testName=ai-astar) 18:45:56 INFO - PROCESS | 13419 | RSS: Main: 245465088 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | __start_tp_report 18:45:56 INFO - PROCESS | 13419 | _x_x_mozilla_page_load 18:45:56 INFO - PROCESS | 13419 | _x_x_mozilla_page_load_details 18:45:56 INFO - PROCESS | 13419 | |i|pagename|runs| 18:45:56 INFO - PROCESS | 13419 | |0;ai-astar;104;113;112;113;114;112;112;113;111;111 18:45:56 INFO - PROCESS | 13419 | |1;audio-beat-detection;148;118;118;162;120;119;119;117;144;120 18:45:56 INFO - PROCESS | 13419 | |2;audio-dft;161;163;160;159;159;160;159;160;159;158 18:45:56 INFO - PROCESS | 13419 | |3;audio-fft;75;75;76;109;75;75;75;75;100;76 18:45:56 INFO - PROCESS | 13419 | |4;audio-oscillator;81;82;110;81;82;82;98;82;82;116 18:45:56 INFO - PROCESS | 13419 | |5;imaging-gaussian-blur;116;117;116;117;116;117;116;117;117;117 18:45:56 INFO - PROCESS | 13419 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;145;144 18:45:56 INFO - PROCESS | 13419 | |7;imaging-desaturate;85;84;84;84;85;84;85;84;85;85 18:45:56 INFO - PROCESS | 13419 | |8;json-parse-financial;49;52;51;55;50;49;49;49;50;50 18:45:56 INFO - PROCESS | 13419 | |9;json-stringify-tinderbox;60;60;61;60;61;61;60;61;61;61 18:45:56 INFO - PROCESS | 13419 | |10;stanford-crypto-aes;75;74;75;74;74;75;73;74;74;80 18:45:56 INFO - PROCESS | 13419 | |11;stanford-crypto-ccm;126;127;129;121;119;120;129;120;158;120 18:45:56 INFO - PROCESS | 13419 | |12;stanford-crypto-pbkdf2;159;158;151;152;153;152;158;154;153;156 18:45:56 INFO - PROCESS | 13419 | |13;stanford-crypto-sha256-iterative;62;63;63;62;62;63;63;70;60;63 18:45:56 INFO - PROCESS | 13419 | __end_tp_report 18:45:56 INFO - PROCESS | 13419 | __start_cc_report 18:45:56 INFO - PROCESS | 13419 | _x_x_mozilla_cycle_collect,1563 18:45:56 INFO - PROCESS | 13419 | __end_cc_report 18:45:56 INFO - PROCESS | 13419 | __startTimestamp1486089956616__endTimestamp 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | ------- Summary: start ------- 18:45:56 INFO - PROCESS | 13419 | Number of tests: 14 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.50 stddev:2.80 (2.5%) stddev-sans-first:1.00 18:45:56 INFO - PROCESS | 13419 | Values: 104.0 113.0 112.0 113.0 114.0 112.0 112.0 113.0 111.0 111.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:120.00 stddev:16.40 (13.7%) stddev-sans-first:15.80 18:45:56 INFO - PROCESS | 13419 | Values: 148.0 118.0 118.0 162.0 120.0 119.0 119.0 117.0 144.0 120.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.40 (0.9%) stddev-sans-first:1.41 18:45:56 INFO - PROCESS | 13419 | Values: 161.0 163.0 160.0 159.0 159.0 160.0 159.0 160.0 159.0 158.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:12.52 (16.7%) stddev-sans-first:13.08 18:45:56 INFO - PROCESS | 13419 | Values: 75.0 75.0 76.0 109.0 75.0 75.0 75.0 75.0 100.0 76.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:82.00 stddev:13.42 (16.4%) stddev-sans-first:13.87 18:45:56 INFO - PROCESS | 13419 | Values: 81.0 82.0 110.0 81.0 82.0 82.0 98.0 82.0 82.0 116.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 18:45:56 INFO - PROCESS | 13419 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 18:45:56 INFO - PROCESS | 13419 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 144.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:45:56 INFO - PROCESS | 13419 | Values: 85.0 84.0 84.0 84.0 85.0 84.0 85.0 84.0 85.0 85.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:1.90 (3.8%) stddev-sans-first:1.94 18:45:56 INFO - PROCESS | 13419 | Values: 49.0 52.0 51.0 55.0 50.0 49.0 49.0 49.0 50.0 50.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 18:45:56 INFO - PROCESS | 13419 | Values: 60.0 60.0 61.0 60.0 61.0 61.0 60.0 61.0 61.0 61.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.50 stddev:1.93 (2.6%) stddev-sans-first:2.05 18:45:56 INFO - PROCESS | 13419 | Values: 75.0 74.0 75.0 74.0 74.0 75.0 73.0 74.0 74.0 80.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:126.50 stddev:11.63 (9.2%) stddev-sans-first:12.33 18:45:56 INFO - PROCESS | 13419 | Values: 126.0 127.0 129.0 121.0 119.0 120.0 129.0 120.0 158.0 120.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.00 stddev:2.91 (1.9%) stddev-sans-first:2.62 18:45:56 INFO - PROCESS | 13419 | Values: 159.0 158.0 151.0 152.0 153.0 152.0 158.0 154.0 153.0 156.0 18:45:56 INFO - PROCESS | 13419 | 18:45:56 INFO - PROCESS | 13419 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:2.60 (4.1%) stddev-sans-first:2.73 18:45:56 INFO - PROCESS | 13419 | Values: 62.0 63.0 63.0 62.0 62.0 63.0 63.0 70.0 60.0 63.0 18:45:56 INFO - PROCESS | 13419 | -------- Summary: end -------- 18:45:56 INFO - PROCESS | 13419 | 18:45:57 INFO - TEST-INFO | 13419: exit 0 18:45:57 INFO - TEST-OK | kraken | took 60322ms 18:45:57 INFO - SUITE-END | took 1174s 18:45:57 INFO - Completed test suite (00:19:34) 18:45:57 INFO - javascript benchmark 18:45:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3169.080966519709, 3203.087120170751, 3212.662670419606, 3234.0459564428493, 3254.349130173964, 5321.733913304334, 5367.302250707403, 5378.139497680372, 5396.703181325026, 5422.484863937924, 5451.04606693828, 5458.808941687042, 5470.425605311445, 5475.425080366564, 5486.945130548694, 4932.704037757728, 4933.777979990891, 4995.600351971833, 5019.84940451787, 5031.572316353115, 4796.664233503656, 4813.542713442217, 4891.0, 4899.755012249373, 4954.7274899880495, 4986.5761410280165, 5008.023435430093, 5017.0, 5019.447860735316, 5029.220470827027, 5020.221865610836, 5036.395632524121, 5040.697558146487, 5042.218456139268, 5072.492750724953, 4820.228763397839, 4861.513848615145, 4867.099586576494, 4930.359053323045, 4957.983613359253, 5291.4708529147165, 5352.384475785296, 5353.464653534655, 5360.061989151883, 5485.6434331768305, 5887.7939272125695, 5892.292924849003, 5898.882022359572, 5902.645841249496, 5904.527637788967, 5627.7467513961565, 5693.943060569382, 5866.823995280149, 5880.500157486619, 5932.673702946337, 5417.8103766367985, 5453.2092846537425, 5491.807786727445, 5530.5852061095575, 5534.501894829405, 6029.547783916224, 6033.668148251888, 6033.909491357633, 6035.969820150871, 6049.274087109487, 5475.123980163155, 5502.119660854324, 5788.971055144697, 5794.072948328331, 5795.043817770105, 5748.425157484218, 5784.334801497797, 5804.564657650693, 5847.707614619252, 5860.120981852756, 5821.650700957872, 5822.301323841166, 5825.359210486843, 5828.6211396259105, 5829.067349224104, 5510.476504732044, 5842.357340692521, 5846.210761547137, 5853.7365818538265, 5880.529557635379, 4447.288433850568, 4451.777411129496, 4470.709403888737, 4471.105778844244, 4520.525344838745, 3981.6217459341315, 4011.939820902689, 4012.7987579432274, 4013.9397909031386, 4022.255882661717, 5453.536449401845, 5467.17992301158, 5472.863178420572, 5475.0, 5499.285092937893, 5581.0, 5634.126710359901, 5768.192453056576, 5773.047447171254, 5789.131630255495, 5554.250176226238, 5560.888782224333, 5567.443255674481, 5574.247476590608, 5592.049351610236, 5033.84898453047, 5044.596432285409, 5071.594272458268, 5091.159958606789, 5100.260462232955, 5732.799352022659, 5748.942510574924, 5804.477597016289, 5842.152887831326, 5952.85117872048, 5740.799072032459, 5744.569157313302, 5745.74144163522, 5765.971170144123, 5775.653460792198, 5911.793087241926, 5926.4666180044005, 5941.1385349125, 5950.642961422329, 6001.309849367379, 5373.650712703662, 5375.5162035417, 5650.039493286234, 5701.287339082614, 5704.030314846403, 5741.827745167651, 5841.269841269841, 5856.238688970407, 5880.441358070976, 5883.264591926009, 5640.774369025193, 5678.14827775837, 5694.117411801178, 5703.401142879938, 5706.857328566819, 5464.071107911744, 5719.2279042328755, 5720.227769251098, 5720.914186287292, 5726.398728133486, 5457.727113644222, 5557.36090349615, 5562.471565201299, 5604.719764011865, 5698.914516282342, 5717.709919852222, 5737.569682273763, 5748.051571490657, 5763.94236057634, 5764.481196692318, 5433.293671822637, 5491.643043202179, 5492.368377636463, 5618.943810562006, 5619.185218143428], "value": 5354.717444797673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.3258682862022, 623.9656818874962, 634.5241069198103, 655.5312951239863, 667.3726696904909, 35644.10889727755, 46381.53618463814, 46531.30203046957, 46866.765666171705, 47074.764626176824, 47993.52006479934, 47999.28001079986, 48448.0, 48487.0, 48539.0, 8339.12439193885, 8671.393002489844, 8783.47299162049, 8823.823523529525, 8824.911750882473, 8457.196566326189, 8605.913940860573, 8609.655613775441, 10613.946930265376, 10863.29388589743, 10694.0, 10725.731856703605, 10729.248952573324, 10731.678049658483, 10731.83902241467, 10481.371117732922, 10540.420276884768, 10542.051215390651, 10550.0, 10561.0, 10757.085647719954, 10830.566777328897, 10831.133509319314, 10832.67501974942, 10834.187435942256, 44512.55487445149, 44711.32933006008, 44716.0, 44723.77638111789, 44848.3272750909, 3169.952450713241, 3309.6855798699085, 3315.469524876032, 3381.695647391722, 3549.893503194908, 33502.6649733502, 33539.0, 33799.662003380145, 33829.0, 34988.30023399543, 3476.2699833035094, 3560.305740380627, 3562.358775420423, 3581.4448760442174, 3610.079429745398, 337729.11354432424, 338291.0, 338670.0, 338678.0, 339334.30332848424, 33851.661483385345, 34849.30301393958, 64848.378790530616, 65198.67400662966, 65334.69330613361, 41545.0, 41650.37524437136, 41656.0, 41685.37471937924, 41711.7914410426, 17144.571385715455, 17267.82732172662, 17318.0, 17324.65350692979, 17326.480205593853, 12902.870971290167, 12910.419031143621, 12917.483300667867, 12920.160189587647, 12934.741305173844, 8986.820263594691, 9130.132637399436, 9242.815143697222, 9598.32811703188, 9621.51892405377, 3601.6758491735345, 3629.4374371972385, 3648.1062139775395, 3673.522442082512, 3702.851885924533, 954.1698722111807, 1034.4879284753945, 1047.9685609431576, 1153.8595210787805, 1175.5944199251244, 31852.203694907814, 32553.186170345933, 33433.83283083569, 33793.32413351719, 34142.48786268208, 1111.1388673777888, 1113.8329250612474, 1177.2465622001926, 1191.6365508519984, 1240.1257113734976, 15103.697926041637, 17761.822381776274, 18269.269229230948, 18914.716279255823, 19318.613627727365, 478987.0, 486084.0, 487177.0, 489056.0, 489071.0, 60698.0, 61233.0, 61247.0, 61261.38738612557, 61340.0, 1311.0, 1375.1955106262749, 1376.1399125546334, 1378.4486205517674, 1417.4471955937192, 31918.840405797822, 33653.49519757255, 34698.65301346954, 34981.47527787136, 35176.47235291524, 53001.0, 53900.46099539111, 53932.460675394315, 54035.0, 54086.459135409714, 358707.0, 362079.0, 363788.0, 366003.0, 368207.27170912863, 27849.303767405978, 28588.285292867844, 30925.845370773, 31607.84196079005, 31667.52498712567, 49437.75281123715, 49498.0, 50192.24711629401, 50385.0, 50451.49548504468, 41533.0, 42047.57952420437, 42279.788601056796, 42327.3650895243, 43758.56241437545, 42077.36883946682, 42804.78597606992, 43000.56999429966, 44072.779636101615, 44207.55792442035, 42916.570834291255, 46547.30179047249, 46629.53370466252, 47787.522124779694, 47919.28121078257, 35935.101622459646, 38697.80651096727, 39206.0, 39243.80378098091, 39435.81692549228, 29067.965242432827, 29421.26446838846, 29569.112926612237, 29820.552691709207, 29941.70058299389], "value": 19719.69194600139, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.46673158065573, 413.5512968429254, 422.4887885658353, 428.59497774603005, 434.600167845582, 18883.811161888378, 20377.0, 20647.38057858263, 20863.47841303968, 21274.893625531888, 17598.120093995312, 17815.287388504476, 18340.72488912664, 18723.25106995722, 19446.22215111394, 5182.6631268967585, 5593.0, 5637.520810731093, 5642.407547207546, 5644.4637759413, 4240.194363071022, 5575.944240557604, 5659.801906933259, 5661.7735290588535, 5666.376698563155, 4513.14250292445, 4538.251188553884, 6342.682865856688, 6426.742930282783, 6446.387593178641, 6263.6241825490615, 6397.200349956255, 6439.484841212715, 6440.355964403546, 6441.935580644181, 5766.135079738031, 5777.682227477487, 5789.334226563957, 5790.536757059467, 5791.0, 23639.881800590887, 23654.290371288716, 23762.0, 24258.87870560636, 24462.143824966042, 2410.8418663752836, 2486.76375744304, 2680.557707978181, 2902.492063888811, 3132.7938743583654, 14852.925735371362, 14921.0, 15034.473793417177, 15349.846501534954, 15466.30401631918, 1915.348781414326, 1967.931122410723, 2019.3538067818304, 2034.7049677796786, 2055.075216152737, 62907.0, 73946.63026684833, 74432.0, 82281.58859205665, 86885.56557217236, 10583.576656933636, 10708.30396024256, 10886.346819190716, 10965.56137754497, 10968.122550195965, 15099.320530575931, 15297.158656274012, 15408.537743867702, 15497.380104795908, 15507.844921550866, 8074.798130046678, 8084.878726819103, 8105.878411823827, 8132.390070744599, 8611.440256383315, 7590.658420371096, 7606.315431611071, 7619.352355049772, 7808.102068262109, 7868.960655196687, 6317.6209427434505, 6439.871202575922, 6467.547271690936, 6475.546711730133, 6536.934630653727, 1927.575933294673, 1943.3878328326532, 1956.814102660245, 1968.6948522978962, 1971.8521110916738, 970.3789574672215, 1017.2828156149897, 1051.6109039655223, 1117.8323251512338, 1137.4995002199005, 19198.13608387626, 19506.414807555797, 20508.79491205069, 20723.0, 21094.578108437745, 1047.324475713176, 1065.8934106589434, 1086.9456527173609, 1109.7003808971533, 1143.9084873210122, 10749.78500429987, 11700.70748231301, 11707.356095414663, 11777.0, 11784.116191285688, 477188.0, 484457.0, 487439.0, 496206.0, 496633.0, 81751.0, 81760.0, 81785.59107204546, 81910.59044704738, 81939.0, 1035.761774791802, 1081.9026287634151, 1099.4667586220676, 1106.4080716816466, 1114.7993361194901, 17388.391406301223, 17540.58332126625, 17817.910910445364, 17830.46508604744, 17999.19003644813, 40185.1962960745, 40561.391579126925, 41475.7926210367, 42135.7893210532, 42878.35682464703, 51369.229461558854, 51882.74058629683, 52724.472755271956, 53044.20433693421, 53763.0, 8677.956610216908, 8794.60424280896, 8931.19619234272, 8931.73204803857, 9103.681371151977, 9308.0, 12926.870731292944, 13085.869141308465, 13697.726045478836, 14030.29848507591, 11326.603568875049, 11340.773184536098, 11641.41792910368, 11932.105092117952, 12360.0, 8418.284445821928, 11067.944660276647, 11611.593594224161, 11849.526018959144, 11861.347625880659, 11454.0, 11493.712657183638, 11620.76758464809, 11779.29324240514, 12181.685479758817, 8779.679459343486, 8905.821883562163, 8979.910200897908, 9048.276137909083, 9165.541722913695, 10458.424786636504, 10734.570617175226, 11207.607733729606, 11245.0, 11438.771224575295], "value": 9481.591778143493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.82195903553, 1438.7554115800317, 1485.8217013958324, 1609.4769200009991, 1793.4978206102296, 7427.591482468469, 7847.21527847215, 8494.362922780792, 8738.519381434035, 8744.825103497926, 8726.38915275931, 8776.034636190014, 8815.603297851596, 8816.823663526726, 8820.691275805348, 2211.8230541556677, 2248.5952528544854, 2253.1325439705747, 2254.120892851789, 2257.0858802185057, 2378.4172877645, 2437.8415402998835, 2443.621238707994, 2459.32368598635, 2461.396957745355, 2358.2335740884196, 2389.928302150938, 2402.7236867760257, 2440.719317278518, 2441.3042282949364, 2280.441291883491, 2300.654901764732, 2332.661764044204, 2335.5912715274844, 2361.97638023621, 2328.6623439601162, 2344.800691941187, 2344.906203751848, 2349.3774149850306, 2350.670906073151, 8252.092269850311, 8546.786330341729, 8553.059163492011, 8563.957180214122, 8737.388382813206, 5132.255822905658, 5222.216667499867, 5259.263703081571, 5268.4994925482015, 5369.973150134263, 2996.715312045374, 3244.902652920416, 3281.4913688378338, 3301.042697617688, 3303.2072302647193, 979.7893452907659, 983.7343917142404, 998.3510718033264, 1077.7628921637227, 1084.2572837606265, 11768.705782355424, 11971.102167337485, 12127.39363031845, 12444.377781111, 12461.127721059529, 4493.505714371417, 4507.706999045117, 4597.241455159928, 4624.930626040612, 4686.8125274988615, 9757.073078057572, 9783.72811534496, 9787.657431989846, 9788.168005719452, 9789.265805064648, 5277.630565860353, 5279.815206467755, 5280.735963201824, 5281.102212623863, 5281.471852814765, 4189.72766770159, 4232.3016702243785, 4873.19592267279, 4925.162722337212, 4928.0, 3880.8447662093763, 3991.560928297885, 4007.779572123503, 4024.4969378827645, 4095.6109169629435, 1225.96935076622, 1227.2943057741834, 1237.1216436330126, 1242.819791130299, 1247.5009996001672, 953.2469349214182, 988.8022395520925, 997.4563862694848, 998.6604554451376, 999.1906555689915, 5857.690404921291, 5894.056950887837, 5928.407159284037, 5944.0, 5993.880122397528, 441.3556207936385, 448.39914514550657, 449.3686370649243, 1009.8687170667766, 1020.9795804083876, 4023.5775243599587, 4131.938020929689, 4135.503739551213, 4147.025449019478, 4174.3321068628875, 10587.100096491731, 11121.110311175087, 11122.888771112348, 11123.777524449466, 11127.387993660426, 10961.0, 10994.450277486094, 11007.284526505751, 11019.944900275448, 11042.503087361089, 8127.715529956423, 8305.626246818907, 8336.708215212439, 8341.66633334672, 8342.706880433421, 5792.160136780059, 6056.273247210363, 6061.454469097803, 6087.239095113111, 6102.237220347457, 9913.45475998827, 9966.451845148296, 10057.798844023226, 10125.898741012496, 10153.949230254097, 10111.24165687591, 10191.03185197405, 10226.028527289896, 10232.23258255619, 10233.181345492492, 6687.297833727389, 7517.586532740533, 7573.318401343906, 7687.423443241891, 7860.606969651609, 8487.36344774132, 8661.91338086611, 8665.306775457822, 8668.78328041804, 8670.869936951078, 7641.1212710539, 7651.693932242648, 7797.727079552188, 7844.058712954482, 7927.801804954922, 7309.634518274172, 7327.340539351484, 7345.22875098107, 7362.926370736438, 7406.0, 6576.868462630816, 6669.299723529154, 6670.099536562696, 6671.166104236971, 6712.966435167793, 4811.302361157683, 4827.251775974589, 4892.926606100982, 5005.249212618136, 5123.692578445305, 4659.60393366554, 4663.813447462064, 4764.2615394613895, 4833.444153922203, 4839.733814640229], "value": 4539.699497343787, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.9525466434698, 342.9365567370037, 352.18820618474405, 354.4771462093412, 355.9394902866512, 5413.188021796727, 5439.728013599324, 5453.400125986138, 5461.726913654321, 5469.78120875165, 5092.108880945829, 5159.458256883029, 5221.138512145491, 5241.764120614572, 5363.973180134104, 4183.3515805050265, 4189.748615083089, 4197.656749840053, 4199.160167966404, 4203.579642035803, 4231.767252801094, 4235.512916014667, 4239.872803815875, 4243.215005224028, 4244.617984381405, 4121.649659778923, 4160.39674247234, 4167.5205302117765, 4199.3281075028, 4210.073783767566, 4179.059711564904, 4187.466582717473, 4188.81150348235, 4193.720915120872, 4195.8741237762915, 3720.2001569662384, 3722.994791406305, 3742.0, 3742.3450896093077, 3752.19327844512, 5036.294918711384, 5158.0, 5196.714180720059, 5200.595839123415, 5215.191645294985, 4822.831200907952, 4838.927416088762, 4858.46556878743, 4891.608671306322, 4893.437254715717, 5572.442755724435, 5596.496315331639, 5628.493435590775, 5639.746211420496, 5654.9151762723595, 3878.4570160177313, 4066.755994640331, 4158.7296825706235, 4169.332906734908, 4182.330827067654, 5797.449242321973, 5805.477507024388, 5807.622504537191, 5835.416458354173, 5842.269716285465, 4159.750414975111, 4162.501499460192, 4171.728837625545, 4172.395002724588, 4180.306975674856, 4117.5882411758585, 4212.347086201644, 4221.91556168881, 4223.049813791872, 4234.470691163605, 4754.597393768847, 4771.451283102488, 4776.0, 4800.183968725325, 4808.927866081942, 5606.775728970877, 5626.2685850839125, 5636.802711905064, 5656.547476201894, 5679.460451257124, 1368.0423703407655, 1372.3549931532164, 1386.6949271160329, 1394.9607542380788, 1402.5441731437325, 935.9906400935904, 956.9665061722945, 967.9642782223094, 974.2985050763439, 979.6179489998906, 5051.0, 5283.0, 5290.232916227125, 5298.735063246822, 5322.0, 5492.286002819687, 5503.642263252956, 5561.499465048084, 5574.581906356958, 5659.745311460912, 5237.973810130925, 5254.266092189569, 5265.631405801557, 5268.28878101459, 5282.894342113136, 2822.911045826074, 2950.660674022472, 2956.2461572298926, 2961.466935951549, 2969.7475714564503, 5797.594168408255, 5802.65184088956, 5819.555786569651, 5821.708914554255, 5832.329282132524, 5690.748035432198, 5702.344230413555, 5714.428557144252, 5729.455701708414, 5732.570057245724, 5647.265855438766, 5766.711664416763, 5772.624779389326, 5830.3586605473365, 5842.941570584239, 5550.75021624036, 5553.222968649993, 5562.16567514876, 5593.4126916674795, 5625.284288291947, 5287.339082614673, 5518.613697041169, 5527.336719593674, 5544.362398324244, 5591.552675786009, 5742.540596752333, 5749.223854779719, 5771.59598828078, 5793.94206057934, 5805.603721822781, 3970.900727481836, 3971.52341718984, 3998.100427403811, 4009.4787677601726, 4019.517657881073, 5389.326334208224, 5525.585581081355, 5553.750081246434, 5745.741441635053, 5763.250777398911, 5617.9719101404235, 5630.971845140748, 5639.351474580311, 5648.011597970257, 5648.774049037993, 5489.862753431196, 5571.749271282721, 5637.43625637433, 5661.405552417102, 5667.943320566907, 5536.889262214814, 5548.500634942713, 5571.9442805571425, 5572.721363931868, 5578.804741834017, 5609.943900561106, 5627.577931655224, 5640.294963129609, 5643.689597072036, 5690.0, 5305.750044627823, 5446.945530544803, 5469.562435005269, 5493.533049690661, 5502.394736578893], "value": 4258.374099816631, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.6336987916887, 773.2615352338518, 774.9573773442462, 808.9474184178032, 839.4627438439396, 13903.16581005139, 14535.200563968965, 14877.776833347509, 15381.69236615267, 15558.0, 13891.583252502416, 14088.36602352894, 14167.78748318776, 14240.14559126453, 14250.572482825506, 842.2125312832491, 860.565414465694, 860.8751730999002, 862.094800459515, 862.6937437209795, 719.8380364418016, 735.6101266328827, 827.2430725885807, 843.0, 845.0746432656232, 912.5254867468946, 915.50562696144, 915.8626206069076, 916.5921165081542, 916.724982505249, 906.9909300906972, 907.0838453162321, 908.5366463103799, 910.6084383715, 911.007002367416, 668.2382084423713, 668.3183153183774, 668.9598624082522, 669.1434963247052, 669.357416879796, 13679.452821887213, 14053.297335133202, 14124.576262712135, 14154.292285385689, 15172.165530895796, 13809.447622095104, 14176.149431034068, 14269.500567480089, 14354.210518421483, 14462.421503139862, 11386.373749443777, 11542.769144617145, 11551.306921584648, 11705.122115841346, 11793.0, 3555.9466608000903, 3559.1280136366754, 3579.19468119671, 3585.4621806728937, 3685.3919103347916, 66241.33758662448, 66478.66760666166, 66673.66663166702, 66986.66506667485, 68427.0, 10747.570097196181, 10762.515686794113, 10815.459227038617, 10851.655193711826, 11183.329000259848, 22096.33710988673, 22103.889480552494, 22159.22442714497, 22160.113595456325, 22191.889040554695, 7552.131504877009, 7710.34462070719, 7758.185390534026, 7921.524708517507, 8234.794130146794, 4417.8232870684815, 4472.597466228055, 4503.527129651405, 4604.8388306409115, 4636.026434448803, 3180.3957248122783, 3260.6413294537583, 3293.86824527021, 3296.950545741816, 3306.7189288910713, 547.5482726750447, 549.5246611680867, 555.630505713704, 559.4098226371136, 561.738791461964, 718.4862823081418, 724.5435375713267, 725.5320318394606, 731.4696844787486, 733.5342057793233, 8699.54265261481, 8860.601272942733, 9017.0, 9041.728748137566, 9188.678396256098, 474.9738764367993, 567.9176519404663, 568.6275489554348, 582.1994757208838, 618.2797041446664, 4386.188555117314, 4474.910501790011, 4576.473705523841, 4595.540445955377, 4657.161710892072, 72194.27805721981, 73215.0, 74909.62545187348, 93630.53184734033, 97128.51435742776, 70672.64663676648, 71901.0, 72394.63802680952, 72994.0, 74366.6281668599, 53038.0, 65403.0, 65752.67123664351, 67033.66483167553, 67567.3243267561, 9581.0, 9896.0, 10212.846807298045, 10216.846747298945, 10244.846327304947, 45619.0, 46197.53802461932, 46251.306230407245, 46448.0, 47520.2871956914, 58225.417745823695, 61434.38565614287, 62968.0, 64165.35834641594, 64995.0, 12386.0, 12528.123031387715, 12631.93684031574, 12663.81004284918, 12780.360981951051, 14209.360578774187, 14238.860891128892, 14363.85636143667, 14410.783838242225, 15238.923805380902, 13176.03631466047, 13398.06213565041, 13630.318484075558, 13842.169469831842, 13860.930695346458, 13178.616245294106, 13537.1200871943, 13730.794038089236, 13741.381637826122, 13851.238181900093, 8241.316453167625, 11702.239354441934, 12390.504379824706, 12487.438065286902, 12880.098393112392, 10111.64609238673, 10327.431991240554, 10821.56713731442, 10941.945290273497, 11170.218084733991, 9726.513674316115, 10112.949435252776, 10438.21713371485, 11157.88842111601, 11260.155488338243], "value": 7095.840902834234, "unit": "score"}], "name": "dromaeo_css", "value": 7183.006988459158, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 113.0, 114.0, 112.0, 112.0, 113.0, 111.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 118.0, 162.0, 120.0, 119.0, 119.0, 117.0, 144.0, 120.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 160.0, 159.0, 159.0, 160.0, 159.0, 160.0, 159.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 109.0, 75.0, 75.0, 75.0, 75.0, 100.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 110.0, 81.0, 82.0, 82.0, 98.0, 82.0, 82.0, 116.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 144.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 52.0, 51.0, 55.0, 50.0, 49.0, 49.0, 49.0, 50.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 74.0, 74.0, 75.0, 73.0, 74.0, 74.0, 80.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 129.0, 121.0, 119.0, 120.0, 129.0, 120.0, 158.0, 120.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 151.0, 152.0, 153.0, 152.0, 158.0, 154.0, 153.0, 156.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 62.0, 63.0, 63.0, 70.0, 60.0, 63.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1447.6, "alertThreshold": 2.0}]} 18:45:57 INFO - Return code: 0 18:45:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:45:57 INFO - # TBPL SUCCESS # 18:45:57 INFO - Running post-action listener: _resource_record_post_action 18:45:57 INFO - [mozharness: 2017-02-03 02:45:57.956167Z] Finished run-tests step (success) 18:45:57 INFO - Running post-run listener: _resource_record_post_run 18:45:58 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 328675328; Read time: 116; Write time: 270464 18:45:58 INFO - TinderboxPrint: CPU usage
13.0% 18:45:58 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 116 18:45:58 INFO - TinderboxPrint: I/O write bytes / time
328,675,328 / 270,464 18:45:58 INFO - TinderboxPrint: CPU idle
8,260.0 (87.0%) 18:45:58 INFO - TinderboxPrint: CPU user
1,187.2 (12.5%) 18:45:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:45:58 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 105275392; Read time: 0; Write time: 95452 18:45:58 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 215674880; Read time: 100; Write time: 174532 18:45:58 INFO - Running post-run listener: _upload_blobber_files 18:45:58 INFO - Blob upload gear active. 18:45:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:45:58 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 18:45:58 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:45:58 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:45:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:45:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:45:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:46:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:02 INFO - (blobuploader) - INFO - Done attempting. 18:46:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:46:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:46:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:02 INFO - (blobuploader) - INFO - Done attempting. 18:46:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:46:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:46:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:03 INFO - (blobuploader) - INFO - Done attempting. 18:46:03 INFO - (blobuploader) - INFO - Iteration through files over. 18:46:03 INFO - Return code: 0 18:46:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:46:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:46:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5112addf84aedf406d01138ced3089097082c25d8fd86b52ce67ae2d0f2f64f257c87caddcf0c2999ede2ff7de3c1e618ca9fd6e0b0cbbe338f387745278d732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec8d8583b062eab7c5a651998ed1329c494b36a4292c09ace8f2a7806b760086c7320db6b0a392ada02fd95b473d7d8b8980111e24aa36ad5d3e7393f9e34a89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dae968c4b1cf77c0d2db8c6d467674588d906c061a4d175310e992e492134527bfddeb7b11c21b94953c1038215185a7aac58428d524ab2ca7216edb3df6b4d"} 18:46:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:46:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:46:03 INFO - Contents: 18:46:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5112addf84aedf406d01138ced3089097082c25d8fd86b52ce67ae2d0f2f64f257c87caddcf0c2999ede2ff7de3c1e618ca9fd6e0b0cbbe338f387745278d732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec8d8583b062eab7c5a651998ed1329c494b36a4292c09ace8f2a7806b760086c7320db6b0a392ada02fd95b473d7d8b8980111e24aa36ad5d3e7393f9e34a89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dae968c4b1cf77c0d2db8c6d467674588d906c061a4d175310e992e492134527bfddeb7b11c21b94953c1038215185a7aac58428d524ab2ca7216edb3df6b4d"} 18:46:03 INFO - Running post-run listener: copy_logs_to_upload_dir 18:46:03 INFO - Copying logs to upload dir... 18:46:03 INFO - mkdir: /builds/slave/test/build/upload/logs 18:46:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.202740 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-02-02 18:46:03.671499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 18:46:03.675001) ========= 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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 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-Beta/sha512/5112addf84aedf406d01138ced3089097082c25d8fd86b52ce67ae2d0f2f64f257c87caddcf0c2999ede2ff7de3c1e618ca9fd6e0b0cbbe338f387745278d732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec8d8583b062eab7c5a651998ed1329c494b36a4292c09ace8f2a7806b760086c7320db6b0a392ada02fd95b473d7d8b8980111e24aa36ad5d3e7393f9e34a89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dae968c4b1cf77c0d2db8c6d467674588d906c061a4d175310e992e492134527bfddeb7b11c21b94953c1038215185a7aac58428d524ab2ca7216edb3df6b4d"} build_url:https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/M5cE2MZ4Q1e07-uZn8zaBg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5112addf84aedf406d01138ced3089097082c25d8fd86b52ce67ae2d0f2f64f257c87caddcf0c2999ede2ff7de3c1e618ca9fd6e0b0cbbe338f387745278d732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec8d8583b062eab7c5a651998ed1329c494b36a4292c09ace8f2a7806b760086c7320db6b0a392ada02fd95b473d7d8b8980111e24aa36ad5d3e7393f9e34a89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dae968c4b1cf77c0d2db8c6d467674588d906c061a4d175310e992e492134527bfddeb7b11c21b94953c1038215185a7aac58428d524ab2ca7216edb3df6b4d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 18:46:03.699462) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:46:03.699787) ========= 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=af724c981e2a0f424dbabf99000001e7-1486088728.798183-1641151778 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 18:46:03.784512) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 18:46:03.784832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 18:46:03.785128) ========= ========= Total master_lag: 0.16 =========