builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1477261910.34 results: success (0) buildid: 20161023152555 builduid: 70711af8e32d475b8e893340ba40f8c5 revision: a9a41b69f3f9be494ae0653601c08aa388d9afe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.342022) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.343191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.343772) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.358717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.359002) ========= 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=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-23 15:31:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.3M=0s 2016-10-23 15:31:51 (41.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134605 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.507329) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.507638) ========= 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=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013685 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.557130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:50.557490) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a9a41b69f3f9be494ae0653601c08aa388d9afe4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a9a41b69f3f9be494ae0653601c08aa388d9afe4 --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=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-23 15:31:51,321 truncating revision to first 12 chars 2016-10-23 15:31:51,321 Setting DEBUG logging. 2016-10-23 15:31:51,321 attempt 1/10 2016-10-23 15:31:51,321 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a9a41b69f3f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-23 15:31:51,946 unpacking tar archive at: fx-team-a9a41b69f3f9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936896 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:51.507849) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:51.508188) ========= script_repo_revision: a9a41b69f3f9be494ae0653601c08aa388d9afe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:51.508603) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:51.508874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 15:31:51.518622) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-10-23 15:31:51.518905) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:31:52 INFO - MultiFileLogger online at 20161023 15:31:52 in /builds/slave/test 15:31:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:31:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:31:52 INFO - {'append_to_log': False, 15:31:52 INFO - 'base_work_dir': '/builds/slave/test', 15:31:52 INFO - 'blob_upload_branch': 'Fx-Team', 15:31:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:31:52 INFO - 'branch': 'Fx-Team', 15:31:52 INFO - 'buildbot_json_path': 'buildprops.json', 15:31:52 INFO - 'config_files': ('talos/linux_config.py',), 15:31:52 INFO - 'default_actions': ('clobber', 15:31:52 INFO - 'read-buildbot-config', 15:31:52 INFO - 'download-and-extract', 15:31:52 INFO - 'populate-webroot', 15:31:52 INFO - 'create-virtualenv', 15:31:52 INFO - 'install', 15:31:52 INFO - 'run-tests'), 15:31:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:31:52 INFO - 'download_minidump_stackwalk': True, 15:31:52 INFO - 'download_symbols': 'ondemand', 15:31:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:31:52 INFO - 'tooltool.py': '/tools/tooltool.py', 15:31:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:31:52 INFO - '/tools/misc-python/virtualenv.py')}, 15:31:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:31:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:31:52 INFO - 'installer_path': 'installer.exe', 15:31:52 INFO - 'log_level': 'info', 15:31:52 INFO - 'log_name': 'talos', 15:31:52 INFO - 'log_to_console': True, 15:31:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:31:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:31:52 INFO - 'opt_config_files': (), 15:31:52 INFO - 'pip_index': False, 15:31:52 INFO - 'sps_profile': False, 15:31:52 INFO - 'sps_profile_interval': 0, 15:31:52 INFO - 'suite': 'dromaeojs', 15:31:52 INFO - 'system_bits': '32', 15:31:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:31:52 INFO - 'title': 'talos-linux64-ix-053', 15:31:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:31:52 INFO - 'use_talos_json': True, 15:31:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:31:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:31:52 INFO - 'work_dir': 'build'} 15:31:52 INFO - [mozharness: 2016-10-23 22:31:52.382041Z] Running clobber step. 15:31:52 INFO - Running pre-action listener: _resource_record_pre_action 15:31:52 INFO - Running main action method: clobber 15:31:52 INFO - rmtree: /builds/slave/test/build 15:31:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:31:52 INFO - Running post-action listener: _resource_record_post_action 15:31:52 INFO - [mozharness: 2016-10-23 22:31:52.840378Z] Finished clobber step (success) 15:31:52 INFO - [mozharness: 2016-10-23 22:31:52.840476Z] Running read-buildbot-config step. 15:31:52 INFO - Running pre-action listener: _resource_record_pre_action 15:31:52 INFO - Running main action method: read_buildbot_config 15:31:52 INFO - Using buildbot properties: 15:31:52 INFO - { 15:31:52 INFO - "project": "", 15:31:52 INFO - "product": "firefox", 15:31:52 INFO - "script_repo_revision": "production", 15:31:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:31:52 INFO - "repository": "", 15:31:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 15:31:52 INFO - "buildid": "20161023152555", 15:31:52 INFO - "stage_platform": "linux64-pgo", 15:31:52 INFO - "basedir": "/builds/slave/test", 15:31:52 INFO - "buildnumber": 1655, 15:31:52 INFO - "platform": "ubuntu64_hw", 15:31:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:31:52 INFO - "slavebuilddir": "test", 15:31:52 INFO - "branch": "fx-team", 15:31:52 INFO - "revision": "a9a41b69f3f9be494ae0653601c08aa388d9afe4", 15:31:52 INFO - "slavename": "talos-linux64-ix-053", 15:31:52 INFO - "builduid": "70711af8e32d475b8e893340ba40f8c5", 15:31:52 INFO - "repo_path": "integration/fx-team" 15:31:52 INFO - } 15:31:52 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 15:31:52 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 15:31:52 INFO - Running post-action listener: _resource_record_post_action 15:31:52 INFO - [mozharness: 2016-10-23 22:31:52.842829Z] Finished read-buildbot-config step (success) 15:31:52 INFO - [mozharness: 2016-10-23 22:31:52.842917Z] Running download-and-extract step. 15:31:52 INFO - Running pre-action listener: _resource_record_pre_action 15:31:52 INFO - Running main action method: download_and_extract 15:31:52 INFO - mkdir: /builds/slave/test/build/tests 15:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:31:52 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:31:52 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:31:52 INFO - Downloaded 1361 bytes. 15:31:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:31:52 INFO - Using the following test package requirements: 15:31:52 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:31:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:31:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:31:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'jsshell-linux-x86_64.zip'], 15:31:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:31:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:31:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:31:52 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:31:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:31:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:31:52 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:31:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:31:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:31:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:31:52 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:31:52 INFO - Http code: 200 15:31:52 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 15:31:52 INFO - x-amz-cf-id: cYcXfPOwGXSd-nCrCxlzHaOmT6vVPbGgEy01uShAUUy948WjW7qVGg== 15:31:52 INFO - x-amz-replication-status: COMPLETED 15:31:52 INFO - x-amz-version-id: d8M2lYxx9YuBsMmAwdTfdwol89PXFt_4 15:31:53 INFO - Expected file size: 22038538 15:31:53 INFO - Obtained file size: 22038538 15:31:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:31:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:31:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:31:57 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:31:57 INFO - Http code: 200 15:31:57 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 15:31:57 INFO - x-amz-cf-id: VLGVFezFmb461rOIic6Qggmlbfu3FV8pfleJp9rzjWP5W0UINzzZYA== 15:31:57 INFO - x-amz-replication-status: COMPLETED 15:31:57 INFO - x-amz-version-id: d8M2lYxx9YuBsMmAwdTfdwol89PXFt_4 15:31:57 INFO - Expected file size: 22038538 15:31:57 INFO - Obtained file size: 22038538 15:32:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:32:02 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:32:02 INFO - Http code: 200 15:32:02 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 15:32:02 INFO - x-amz-cf-id: 0BBGGMZ4A6Q2GHYcCH3w5AgKq5glj9xiG6nXX3nRmlOeNI3gDWqqLA== 15:32:02 INFO - x-amz-replication-status: COMPLETED 15:32:02 INFO - x-amz-version-id: JZWh_weh4qCLiKAVrFXr9AE39bxb.e2Z 15:32:02 INFO - Expected file size: 14409028 15:32:02 INFO - Obtained file size: 14409028 15:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:02 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:32:02 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:32:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:32:03 INFO - Downloaded 59381412 bytes. 15:32:03 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:32:03 INFO - mkdir: /builds/slave/test/properties 15:32:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:03 INFO - Contents: 15:32:03 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:32:03 INFO - Running post-action listener: _resource_record_post_action 15:32:03 INFO - Running post-action listener: set_extra_try_arguments 15:32:03 INFO - [mozharness: 2016-10-23 22:32:03.554598Z] Finished download-and-extract step (success) 15:32:03 INFO - [mozharness: 2016-10-23 22:32:03.554826Z] Running populate-webroot step. 15:32:03 INFO - Running pre-action listener: _resource_record_pre_action 15:32:03 INFO - Running main action method: populate_webroot 15:32:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:03 INFO - '--tppagecycles', 15:32:03 INFO - '7'], 15:32:03 INFO - 'tests': ['tsvgm']}}, 15:32:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:32:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:32:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['damp', 'tps']}, 15:32:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'tests': ['damp', 'tps']}, 15:32:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['dromaeo_dom']}, 15:32:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['basic_compositor_video']}, 15:32:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:32:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'other-e10s': {'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['a11yr', 15:32:03 INFO - 'ts_paint', 15:32:03 INFO - 'tpaint', 15:32:03 INFO - 'sessionrestore', 15:32:03 INFO - 'sessionrestore_no_auto_restore', 15:32:03 INFO - 'tabpaint']}, 15:32:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['tsvgx', 15:32:03 INFO - 'tsvgr_opacity', 15:32:03 INFO - 'tart', 15:32:03 INFO - 'tscrollx', 15:32:03 INFO - 'cart']}, 15:32:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:32:03 INFO - 'tsvgr_opacity', 15:32:03 INFO - 'tart', 15:32:03 INFO - 'tscrollx', 15:32:03 INFO - 'cart']}, 15:32:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'talos_options': ['--disable-e10s'], 15:32:03 INFO - 'tests': ['tp5o']}, 15:32:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'tests': ['tp5o']}, 15:32:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'talos_options': ['--disable-e10s', 15:32:03 INFO - '--xperf_path', 15:32:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:03 INFO - 'tests': ['tp5n']}, 15:32:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:03 INFO - 'talos_options': ['--xperf_path', 15:32:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:03 INFO - 'tests': ['tp5n']}}, 15:32:03 INFO - 'talos.zip': {'path': '', 15:32:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:03 INFO - Running post-action listener: _resource_record_post_action 15:32:03 INFO - [mozharness: 2016-10-23 22:32:03.573331Z] Finished populate-webroot step (success) 15:32:03 INFO - [mozharness: 2016-10-23 22:32:03.573419Z] Running create-virtualenv step. 15:32:03 INFO - Running pre-action listener: _resource_record_pre_action 15:32:03 INFO - Running main action method: create_virtualenv 15:32:03 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:32:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:03 INFO - Using real prefix '/usr' 15:32:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:04 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:06 INFO - Installing pip.................done. 15:32:06 INFO - Return code: 0 15:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:32:06 INFO - Reading from file tmpfile_stdout 15:32:06 INFO - Output received: 15:32:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:32:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:32:06 INFO - Using env: {'DISPLAY': ':0', 15:32:06 INFO - 'HOME': '/home/cltbld', 15:32:06 INFO - 'LANG': 'en_US.UTF-8', 15:32:06 INFO - 'LANGUAGE': 'en_US:en', 15:32:06 INFO - 'LOGNAME': 'cltbld', 15:32:06 INFO - 'MAIL': '/var/mail/cltbld', 15:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:06 INFO - 'MOZ_NO_REMOTE': '1', 15:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:06 INFO - 'NO_EM_RESTART': '1', 15:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:06 INFO - 'PWD': '/builds/slave/test', 15:32:06 INFO - 'SHELL': '/bin/bash', 15:32:06 INFO - 'SHLVL': '1', 15:32:06 INFO - 'TERM': 'linux', 15:32:06 INFO - 'TMOUT': '86400', 15:32:06 INFO - 'USER': 'cltbld', 15:32:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:06 INFO - '_': '/tools/buildbot/bin/python'} 15:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:32:06 INFO - Cleaning up... 15:32:06 INFO - Return code: 0 15:32:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:32:06 INFO - Using env: {'DISPLAY': ':0', 15:32:06 INFO - 'HOME': '/home/cltbld', 15:32:06 INFO - 'LANG': 'en_US.UTF-8', 15:32:06 INFO - 'LANGUAGE': 'en_US:en', 15:32:06 INFO - 'LOGNAME': 'cltbld', 15:32:06 INFO - 'MAIL': '/var/mail/cltbld', 15:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:06 INFO - 'MOZ_NO_REMOTE': '1', 15:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:06 INFO - 'NO_EM_RESTART': '1', 15:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:06 INFO - 'PWD': '/builds/slave/test', 15:32:06 INFO - 'SHELL': '/bin/bash', 15:32:06 INFO - 'SHLVL': '1', 15:32:06 INFO - 'TERM': 'linux', 15:32:06 INFO - 'TMOUT': '86400', 15:32:06 INFO - 'USER': 'cltbld', 15:32:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:06 INFO - '_': '/tools/buildbot/bin/python'} 15:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:06 INFO - Downloading/unpacking psutil>=3.1.1 15:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:08 INFO - Installing collected packages: psutil 15:32:08 INFO - Running setup.py install for psutil 15:32:08 INFO - building 'psutil._psutil_linux' extension 15:32:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:32:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:32:09 INFO - building 'psutil._psutil_posix' extension 15:32:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:32:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:32:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:09 INFO - Successfully installed psutil 15:32:09 INFO - Cleaning up... 15:32:09 INFO - Return code: 0 15:32:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:09 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:32:09 INFO - Using env: {'DISPLAY': ':0', 15:32:09 INFO - 'HOME': '/home/cltbld', 15:32:09 INFO - 'LANG': 'en_US.UTF-8', 15:32:09 INFO - 'LANGUAGE': 'en_US:en', 15:32:09 INFO - 'LOGNAME': 'cltbld', 15:32:09 INFO - 'MAIL': '/var/mail/cltbld', 15:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:09 INFO - 'MOZ_NO_REMOTE': '1', 15:32:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:09 INFO - 'NO_EM_RESTART': '1', 15:32:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:09 INFO - 'PWD': '/builds/slave/test', 15:32:09 INFO - 'SHELL': '/bin/bash', 15:32:09 INFO - 'SHLVL': '1', 15:32:09 INFO - 'TERM': 'linux', 15:32:09 INFO - 'TMOUT': '86400', 15:32:09 INFO - 'USER': 'cltbld', 15:32:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:09 INFO - '_': '/tools/buildbot/bin/python'} 15:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:32:11 INFO - Installing collected packages: mozsystemmonitor 15:32:11 INFO - Running setup.py install for mozsystemmonitor 15:32:11 INFO - Successfully installed mozsystemmonitor 15:32:11 INFO - Cleaning up... 15:32:11 INFO - Return code: 0 15:32:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:11 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:32:11 INFO - Using env: {'DISPLAY': ':0', 15:32:11 INFO - 'HOME': '/home/cltbld', 15:32:11 INFO - 'LANG': 'en_US.UTF-8', 15:32:11 INFO - 'LANGUAGE': 'en_US:en', 15:32:11 INFO - 'LOGNAME': 'cltbld', 15:32:11 INFO - 'MAIL': '/var/mail/cltbld', 15:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:11 INFO - 'MOZ_NO_REMOTE': '1', 15:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:11 INFO - 'NO_EM_RESTART': '1', 15:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:11 INFO - 'PWD': '/builds/slave/test', 15:32:11 INFO - 'SHELL': '/bin/bash', 15:32:11 INFO - 'SHLVL': '1', 15:32:11 INFO - 'TERM': 'linux', 15:32:11 INFO - 'TMOUT': '86400', 15:32:11 INFO - 'USER': 'cltbld', 15:32:11 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:11 INFO - '_': '/tools/buildbot/bin/python'} 15:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:11 INFO - Downloading/unpacking jsonschema==2.5.1 15:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:32:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:13 INFO - Downloading functools32-3.2.3-2.tar.gz 15:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:32:14 INFO - warning: no files found matching '*.txt' 15:32:14 INFO - no previously-included directories found matching 'build' 15:32:14 INFO - no previously-included directories found matching 'dist' 15:32:14 INFO - no previously-included directories found matching '.git*' 15:32:14 INFO - Installing collected packages: jsonschema, functools32 15:32:14 INFO - Running setup.py install for functools32 15:32:14 INFO - warning: no files found matching '*.txt' 15:32:14 INFO - no previously-included directories found matching 'build' 15:32:14 INFO - no previously-included directories found matching 'dist' 15:32:14 INFO - no previously-included directories found matching '.git*' 15:32:14 INFO - Successfully installed jsonschema functools32 15:32:14 INFO - Cleaning up... 15:32:14 INFO - Return code: 0 15:32:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:14 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:32:14 INFO - Using env: {'DISPLAY': ':0', 15:32:14 INFO - 'HOME': '/home/cltbld', 15:32:14 INFO - 'LANG': 'en_US.UTF-8', 15:32:14 INFO - 'LANGUAGE': 'en_US:en', 15:32:14 INFO - 'LOGNAME': 'cltbld', 15:32:14 INFO - 'MAIL': '/var/mail/cltbld', 15:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:14 INFO - 'MOZ_NO_REMOTE': '1', 15:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:14 INFO - 'NO_EM_RESTART': '1', 15:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:14 INFO - 'PWD': '/builds/slave/test', 15:32:14 INFO - 'SHELL': '/bin/bash', 15:32:14 INFO - 'SHLVL': '1', 15:32:14 INFO - 'TERM': 'linux', 15:32:14 INFO - 'TMOUT': '86400', 15:32:14 INFO - 'USER': 'cltbld', 15:32:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:14 INFO - '_': '/tools/buildbot/bin/python'} 15:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:32:14 INFO - Cleaning up... 15:32:14 INFO - Return code: 0 15:32:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:14 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:32:14 INFO - Using env: {'DISPLAY': ':0', 15:32:14 INFO - 'HOME': '/home/cltbld', 15:32:14 INFO - 'LANG': 'en_US.UTF-8', 15:32:14 INFO - 'LANGUAGE': 'en_US:en', 15:32:14 INFO - 'LOGNAME': 'cltbld', 15:32:14 INFO - 'MAIL': '/var/mail/cltbld', 15:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:14 INFO - 'MOZ_NO_REMOTE': '1', 15:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:14 INFO - 'NO_EM_RESTART': '1', 15:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:14 INFO - 'PWD': '/builds/slave/test', 15:32:14 INFO - 'SHELL': '/bin/bash', 15:32:14 INFO - 'SHLVL': '1', 15:32:14 INFO - 'TERM': 'linux', 15:32:14 INFO - 'TMOUT': '86400', 15:32:14 INFO - 'USER': 'cltbld', 15:32:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:14 INFO - '_': '/tools/buildbot/bin/python'} 15:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:14 INFO - Downloading/unpacking blobuploader==1.2.4 15:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:16 INFO - Downloading blobuploader-1.2.4.tar.gz 15:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:32:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:32:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:17 INFO - Downloading docopt-0.6.1.tar.gz 15:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:32:17 INFO - Installing collected packages: blobuploader, requests, docopt 15:32:17 INFO - Running setup.py install for blobuploader 15:32:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:32:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:32:17 INFO - Running setup.py install for requests 15:32:17 INFO - Running setup.py install for docopt 15:32:17 INFO - Successfully installed blobuploader requests docopt 15:32:17 INFO - Cleaning up... 15:32:17 INFO - Return code: 0 15:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:17 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:32:17 INFO - Using env: {'DISPLAY': ':0', 15:32:17 INFO - 'HOME': '/home/cltbld', 15:32:17 INFO - 'LANG': 'en_US.UTF-8', 15:32:17 INFO - 'LANGUAGE': 'en_US:en', 15:32:17 INFO - 'LOGNAME': 'cltbld', 15:32:18 INFO - 'MAIL': '/var/mail/cltbld', 15:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:18 INFO - 'MOZ_NO_REMOTE': '1', 15:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:18 INFO - 'NO_EM_RESTART': '1', 15:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:18 INFO - 'PWD': '/builds/slave/test', 15:32:18 INFO - 'SHELL': '/bin/bash', 15:32:18 INFO - 'SHLVL': '1', 15:32:18 INFO - 'TERM': 'linux', 15:32:18 INFO - 'TMOUT': '86400', 15:32:18 INFO - 'USER': 'cltbld', 15:32:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:18 INFO - '_': '/tools/buildbot/bin/python'} 15:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:18 INFO - Running setup.py (path:/tmp/pip-OAGHS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:18 INFO - Running setup.py (path:/tmp/pip-ukfEag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:18 INFO - Running setup.py (path:/tmp/pip-iSeV4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:18 INFO - Running setup.py (path:/tmp/pip-FD0nbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:18 INFO - Running setup.py (path:/tmp/pip-_Rlzk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:18 INFO - Running setup.py (path:/tmp/pip-R4nDYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:18 INFO - Running setup.py (path:/tmp/pip-iHwWCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:18 INFO - Running setup.py (path:/tmp/pip-BOSnRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:18 INFO - Running setup.py (path:/tmp/pip-gf4NyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:18 INFO - Running setup.py (path:/tmp/pip-uWNu7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:18 INFO - Running setup.py (path:/tmp/pip-UdTgXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:18 INFO - Running setup.py (path:/tmp/pip-qYK98Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:19 INFO - Running setup.py (path:/tmp/pip-1zakua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:19 INFO - Running setup.py (path:/tmp/pip-CgvNvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:19 INFO - Running setup.py (path:/tmp/pip-xzo_wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:19 INFO - Running setup.py (path:/tmp/pip-amQiS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:19 INFO - Running setup.py (path:/tmp/pip-PRUDeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:32:19 INFO - Running setup.py install for manifestparser 15:32:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:32:19 INFO - Running setup.py install for mozcrash 15:32:19 INFO - Running setup.py install for mozdebug 15:32:19 INFO - Running setup.py install for mozdevice 15:32:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:32:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:32:19 INFO - Running setup.py install for mozfile 15:32:19 INFO - Running setup.py install for mozhttpd 15:32:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozinfo 15:32:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozInstall 15:32:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozleak 15:32:20 INFO - Running setup.py install for mozlog 15:32:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for moznetwork 15:32:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozprocess 15:32:20 INFO - Running setup.py install for mozprofile 15:32:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozrunner 15:32:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:32:20 INFO - Running setup.py install for mozscreenshot 15:32:20 INFO - Running setup.py install for moztest 15:32:21 INFO - Running setup.py install for mozversion 15:32:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:32:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:32:21 INFO - Cleaning up... 15:32:21 INFO - Return code: 0 15:32:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:21 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:32:21 INFO - Using env: {'DISPLAY': ':0', 15:32:21 INFO - 'HOME': '/home/cltbld', 15:32:21 INFO - 'LANG': 'en_US.UTF-8', 15:32:21 INFO - 'LANGUAGE': 'en_US:en', 15:32:21 INFO - 'LOGNAME': 'cltbld', 15:32:21 INFO - 'MAIL': '/var/mail/cltbld', 15:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:21 INFO - 'MOZ_NO_REMOTE': '1', 15:32:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:21 INFO - 'NO_EM_RESTART': '1', 15:32:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:21 INFO - 'PWD': '/builds/slave/test', 15:32:21 INFO - 'SHELL': '/bin/bash', 15:32:21 INFO - 'SHLVL': '1', 15:32:21 INFO - 'TERM': 'linux', 15:32:21 INFO - 'TMOUT': '86400', 15:32:21 INFO - 'USER': 'cltbld', 15:32:21 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:21 INFO - '_': '/tools/buildbot/bin/python'} 15:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:21 INFO - Running setup.py (path:/tmp/pip-o4NLRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:21 INFO - Running setup.py (path:/tmp/pip-7kLYQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:21 INFO - Running setup.py (path:/tmp/pip-7rG7xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:21 INFO - Running setup.py (path:/tmp/pip-PVdb2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:21 INFO - Running setup.py (path:/tmp/pip-zX7I2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:21 INFO - Running setup.py (path:/tmp/pip-HXsqGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:21 INFO - Running setup.py (path:/tmp/pip-gfzxva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:21 INFO - Running setup.py (path:/tmp/pip-GQN1hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:22 INFO - Running setup.py (path:/tmp/pip-oObMMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:22 INFO - Running setup.py (path:/tmp/pip-6mF8sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:22 INFO - Running setup.py (path:/tmp/pip-jRkZCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:22 INFO - Running setup.py (path:/tmp/pip-tSRMlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:22 INFO - Running setup.py (path:/tmp/pip-TojThu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:22 INFO - Running setup.py (path:/tmp/pip-frEhEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:22 INFO - Running setup.py (path:/tmp/pip-0nsTpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:22 INFO - Running setup.py (path:/tmp/pip-ozMLE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:22 INFO - Running setup.py (path:/tmp/pip-9BGZl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:32:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:24 INFO - Downloading blessings-1.6.tar.gz 15:32:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:32:24 INFO - Installing collected packages: blessings 15:32:24 INFO - Running setup.py install for blessings 15:32:24 INFO - Successfully installed blessings 15:32:24 INFO - Cleaning up... 15:32:24 INFO - Return code: 0 15:32:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:25 INFO - Reading from file tmpfile_stdout 15:32:25 INFO - Current package versions: 15:32:25 INFO - argparse == 1.2.1 15:32:25 INFO - blessings == 1.6 15:32:25 INFO - blobuploader == 1.2.4 15:32:25 INFO - docopt == 0.6.1 15:32:25 INFO - functools32 == 3.2.3-2 15:32:25 INFO - jsonschema == 2.5.1 15:32:25 INFO - manifestparser == 1.1 15:32:25 INFO - mozInstall == 1.12 15:32:25 INFO - mozcrash == 0.17 15:32:25 INFO - mozdebug == 0.1 15:32:25 INFO - mozdevice == 0.48 15:32:25 INFO - mozfile == 1.2 15:32:25 INFO - mozhttpd == 0.7 15:32:25 INFO - mozinfo == 0.9 15:32:25 INFO - mozleak == 0.1 15:32:25 INFO - mozlog == 3.4 15:32:25 INFO - moznetwork == 0.27 15:32:25 INFO - mozprocess == 0.23 15:32:25 INFO - mozprofile == 0.28 15:32:25 INFO - mozrunner == 6.12 15:32:25 INFO - mozscreenshot == 0.1 15:32:25 INFO - mozsystemmonitor == 0.3 15:32:25 INFO - moztest == 0.7 15:32:25 INFO - mozversion == 1.4 15:32:25 INFO - psutil == 3.1.1 15:32:25 INFO - requests == 1.2.3 15:32:25 INFO - wsgiref == 0.1.2 15:32:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 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 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:32:25 INFO - Using env: {'DISPLAY': ':0', 15:32:25 INFO - 'HOME': '/home/cltbld', 15:32:25 INFO - 'LANG': 'en_US.UTF-8', 15:32:25 INFO - 'LANGUAGE': 'en_US:en', 15:32:25 INFO - 'LOGNAME': 'cltbld', 15:32:25 INFO - 'MAIL': '/var/mail/cltbld', 15:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:25 INFO - 'MOZ_NO_REMOTE': '1', 15:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:25 INFO - 'NO_EM_RESTART': '1', 15:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:25 INFO - 'PWD': '/builds/slave/test', 15:32:25 INFO - 'SHELL': '/bin/bash', 15:32:25 INFO - 'SHLVL': '1', 15:32:25 INFO - 'TERM': 'linux', 15:32:25 INFO - 'TMOUT': '86400', 15:32:25 INFO - 'USER': 'cltbld', 15:32:25 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:25 INFO - '_': '/tools/buildbot/bin/python'} 15:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:32:25 INFO - Cleaning up... 15:32:25 INFO - Return code: 0 15:32:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c5908f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ff7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, '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': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:32:25 INFO - Using env: {'DISPLAY': ':0', 15:32:25 INFO - 'HOME': '/home/cltbld', 15:32:25 INFO - 'LANG': 'en_US.UTF-8', 15:32:25 INFO - 'LANGUAGE': 'en_US:en', 15:32:25 INFO - 'LOGNAME': 'cltbld', 15:32:25 INFO - 'MAIL': '/var/mail/cltbld', 15:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:25 INFO - 'MOZ_NO_REMOTE': '1', 15:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:25 INFO - 'NO_EM_RESTART': '1', 15:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:25 INFO - 'PWD': '/builds/slave/test', 15:32:25 INFO - 'SHELL': '/bin/bash', 15:32:25 INFO - 'SHLVL': '1', 15:32:25 INFO - 'TERM': 'linux', 15:32:25 INFO - 'TMOUT': '86400', 15:32:25 INFO - 'USER': 'cltbld', 15:32:25 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:25 INFO - '_': '/tools/buildbot/bin/python'} 15:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:32:25 INFO - Cleaning up... 15:32:25 INFO - Return code: 0 15:32:25 INFO - Running post-action listener: _resource_record_post_action 15:32:25 INFO - Running post-action listener: _start_resource_monitoring 15:32:25 INFO - Starting resource monitoring. 15:32:25 INFO - [mozharness: 2016-10-23 22:32:25.583979Z] Finished create-virtualenv step (success) 15:32:25 INFO - [mozharness: 2016-10-23 22:32:25.584443Z] Running install step. 15:32:25 INFO - Running pre-action listener: _resource_record_pre_action 15:32:25 INFO - Running main action method: install 15:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:25 INFO - Reading from file tmpfile_stdout 15:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 15:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:32:25 INFO - Reading from file tmpfile_stdout 15:32:25 INFO - Output received: 15:32:25 INFO - Usage: mozinstall [options] installer 15:32:25 INFO - Options: 15:32:25 INFO - -h, --help show this help message and exit 15:32:25 INFO - -d DEST, --destination=DEST 15:32:25 INFO - Directory to install application into. [default: 15:32:25 INFO - "/builds/slave/test"] 15:32:25 INFO - --app=APP Application being installed. [default: firefox] 15:32:25 INFO - mkdir: /builds/slave/test/build/application 15:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:32:42 INFO - Reading from file tmpfile_stdout 15:32:42 INFO - Output received: 15:32:42 INFO - /builds/slave/test/build/application/firefox/firefox 15:32:42 INFO - Running post-action listener: _resource_record_post_action 15:32:42 INFO - [mozharness: 2016-10-23 22:32:42.783192Z] Finished install step (success) 15:32:42 INFO - [mozharness: 2016-10-23 22:32:42.783475Z] Running run-tests step. 15:32:42 INFO - Running pre-action listener: _resource_record_pre_action 15:32:42 INFO - Running main action method: run_tests 15:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:32:42 INFO - Python 2.7.3 15:32:42 INFO - Return code: 0 15:32:42 INFO - grabbing minidump binary from tooltool 15:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:42 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 0x248e790>, '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 0x248f690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:32:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:32:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:32:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:32:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:32:42 INFO - Return code: 0 15:32:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:32:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:32:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:32:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:32:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:32:42 INFO - Using env: {'DISPLAY': ':0', 15:32:42 INFO - 'HOME': '/home/cltbld', 15:32:42 INFO - 'LANG': 'en_US.UTF-8', 15:32:42 INFO - 'LANGUAGE': 'en_US:en', 15:32:42 INFO - 'LOGNAME': 'cltbld', 15:32:42 INFO - 'MAIL': '/var/mail/cltbld', 15:32:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:32:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:42 INFO - 'MOZ_NO_REMOTE': '1', 15:32:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:42 INFO - 'NO_EM_RESTART': '1', 15:32:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:42 INFO - 'PWD': '/builds/slave/test', 15:32:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:32:42 INFO - 'SHELL': '/bin/bash', 15:32:42 INFO - 'SHLVL': '1', 15:32:42 INFO - 'TERM': 'linux', 15:32:42 INFO - 'TMOUT': '86400', 15:32:42 INFO - 'USER': 'cltbld', 15:32:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676', 15:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:42 INFO - '_': '/tools/buildbot/bin/python'} 15:32:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:32:43 INFO - mozversion application_buildid: 20161023123006 15:32:43 INFO - mozversion application_changeset: a9a41b69f3f9be494ae0653601c08aa388d9afe4 15:32:43 INFO - mozversion application_display_name: Nightly 15:32:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:32:43 INFO - mozversion application_name: Firefox 15:32:43 INFO - mozversion application_remotingname: firefox 15:32:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:32:43 INFO - mozversion application_vendor: Mozilla 15:32:43 INFO - mozversion application_version: 52.0a1 15:32:43 INFO - mozversion platform_buildid: 20161023123006 15:32:43 INFO - mozversion platform_changeset: a9a41b69f3f9be494ae0653601c08aa388d9afe4 15:32:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:32:43 INFO - mozversion platform_version: 52.0a1 15:32:43 INFO - using testdate: 1477261963 15:32:43 INFO - actual date: 1477261963 15:32:43 INFO - starting webserver on 'localhost:37764' 15:32:43 INFO - SUITE-START | Running 2 tests 15:32:43 INFO - TEST-START | dromaeo_css 15:32:43 INFO - operating with platform_type : linux_ 15:32:43 INFO - Initialising browser for dromaeo_css test... 15:32:43 INFO - TEST-INFO | started process 17801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp794uE6/profile http://localhost:37764/getInfo.html) 15:32:47 INFO - PROCESS | 17801 | __metrics Screen width/height:1600/1200 15:32:47 INFO - PROCESS | 17801 | colorDepth:24 15:32:47 INFO - PROCESS | 17801 | Browser inner width/height: 1024/697 15:32:47 INFO - PROCESS | 17801 | __metrics 15:32:48 INFO - PROCESS | 17801 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:32:48 INFO - PROCESS | 17801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:32:48 INFO - PROCESS | 17801 | console.error: PushService: 15:32:48 INFO - PROCESS | 17801 | stateChangeProcessEnqueue: Error transitioning state 15:32:48 INFO - PROCESS | 17801 | UnknownError 15:32:49 INFO - TEST-INFO | 17801: exit 0 15:32:49 INFO - Browser initialized. 15:32:49 INFO - Running cycle 1/1 for dromaeo_css test... 15:32:49 INFO - TEST-INFO | started process 17885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp794uE6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:01 INFO - PROCESS | 17885 | RSS: Main: 171352064 15:33:01 INFO - PROCESS | 17885 | 15:33:53 INFO - PROCESS | 17885 | 1477262033251 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:36:03 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37764/tests/dromaeo/cssquery-ext.html) 15:36:05 INFO - PROCESS | 17885 | RSS: Main: 366231552 15:36:05 INFO - PROCESS | 17885 | 15:39:07 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-ext.html (next: http://localhost:37764/tests/dromaeo/cssquery-jquery.html) 15:39:08 INFO - PROCESS | 17885 | RSS: Main: 273936384 15:39:08 INFO - PROCESS | 17885 | 15:42:10 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37764/tests/dromaeo/cssquery-mootools.html) 15:42:11 INFO - PROCESS | 17885 | RSS: Main: 213385216 15:42:11 INFO - PROCESS | 17885 | 15:45:13 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37764/tests/dromaeo/cssquery-prototype.html) 15:45:13 INFO - PROCESS | 17885 | RSS: Main: 214405120 15:45:13 INFO - PROCESS | 17885 | 15:48:16 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37764/tests/dromaeo/cssquery-yui.html) 15:48:17 INFO - PROCESS | 17885 | RSS: Main: 213311488 15:48:17 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | Cycle 1(1): loaded http://localhost:37764/tests/dromaeo/cssquery-yui.html (next: http://localhost:37764/tests/dromaeo/cssquery-dojo.html) 15:51:20 INFO - PROCESS | 17885 | RSS: Main: 303517696 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | __start_tp_report 15:51:20 INFO - PROCESS | 17885 | _x_x_mozilla_page_load 15:51:20 INFO - PROCESS | 17885 | _x_x_mozilla_page_load_details 15:51:20 INFO - PROCESS | 17885 | |i|pagename|runs| 15:51:20 INFO - PROCESS | 17885 | |0;dojo.html;3045.817250964942;3057.8929737459193;3105.378924215157;3121.578586890769;3122.45357062514;5111.182210846266;5165.896682066356;5289.814856480024;5356.223347614594;5418.864528386793;5243.554297884684;5268.420473747894;5388.541973932211;5397.649152805075;5468.125099984003;4905.460399356068;4930.482299358569;4977.303177555145;4984.551390374865;5013.423456302536;4944;5028.522290382426;5047.545720885119;5049.949500504985;5100.617453690969;4803.471618122004;4990.800367985276;4994.05113028527;5025.497450254964;5107.897842043157;4847.175980083394;4911.312416261726;4917.754112294371;4971.850844474671;4997.250412438127;4877.804887804484;4895.84675940155;4899.7550122494085;4899.995500922302;4961.627877909171;5410.594205434608;5419.83740487786;5423.457654234585;5440.863978400532;5479.232907392969;5573.136163894603;5586.581006424534;5616.1014237721765;5616.803411880605;5743.8564035899435;5461.644993075437;5537.501624853782;5551.2228288039705;5707.200991861101;5720.170575266563;5322.973385133088;5324.41431442541;5325.281087053276;5328.840134795923;5333.679979201261;5673.035583950738;5677.233573467612;5790.94209057904;5793.768249270066;5868.501177399969;5601.551875849922;5650.378458369576;5689.231953686282;5693.5445164387575;5779.075347944393;5722.513586345124;5778.88442231153;5782.132680098019;5784.248047753765;5786.971065144731;5766.538676905838;5776.133579963039;5788.0449725795615;5789.88420231593;5876.764929402776;5446.455354464521;5548.750306236148;5561.944380556223;5636.746346414421;5636.943630563724;4364.10535840152;4396.692231543761;4409.691321607456;4453.532379100213;4465.799039043251;3913.4325522799036;3921;3922.9607703923166;3960.3069462843887;3971.245463361952;5430.0768869292315;5436.130219164994;5436.592255580847;5437.429069947678;5439.048166570834;5583.581231407579;5708.71456427177;5780.855478613068;5846.736896839652;5856.385079566584;5457.345118585716;5491.121420572689;5493.807716729895;5503.091989821634;5549.139883318092;5031.069252188357;5031.77357018935;5037.01778153264;5040.168372218543;5095.694258344512;5775.104858746901;5817.941820581825;5882.205902203148;5950.404959504015;6012.759489620454;5806.5645076618575;5818.76724931015;5820.592558520862;5858.853528661817;5876.3536011037895;5892.823215303548;5934.406559344031;5939.821805345847;5943.613665111754;5958;5478.698671573102;5538.224648549287;5647.632903861236;5653.491185793298;5655.717214139194;5758.3090029196765;5761.884762304814;5816.680082595498;5862.94137058624;5881.470667639933;5697.116946873241;5698.230738850202;5699.5725320600295;5705.942940570541;5706.2011318414625;5478;5545.473180047889;5668.631538949955;5669.489745922887;5675.460831221028;5531.9723401382735;5586.692731899784;5599.244102046172;5602.775888964396;5606.607537472501;5630.74661640219;5651.915221271602;5654.293213348332;5659.26429564154;5664.886702265849;5513.173024046425;5533.059379905345;5605.075162598126;5614.29821272341;5616.550675945833 15:51:20 INFO - PROCESS | 17885 | |1;ext.html;531.2521393852262;583.5360888093966;600.8467840700622;617.576959782549;633.5944995203074;33586.66413335866;42569;43526;43639.12721745567;46861.297080543736;43417.13165736691;43830.56169438305;43834.12331753363;43849.56150438495;43909.341359879625;8100.756977290691;8280.089190189074;8896.555172241378;9054.547272636375;9061.95469022654;10440.112590429822;10714.464276786168;10722.0886224671;11051.115910727123;11061.944690276538;10889.074428673572;10985.285956412847;10993.61522346718;10997.395143267117;11000;8074.394420418463;8262.380321475914;10489.213309001856;10699.251052426333;10730.839037414446;10453.11148552374;10519.894801052045;10582.100521455686;10588.470576471145;10588.94705526475;41121.58878411208;41552.58447415518;41561;41572.79213603913;41604.37593436101;3370.5449764281752;3422.212891035051;3685.7972811495356;3711.2206436648066;4097.078157414588;30447.847760761055;30692;31233;31252;31317;3959.5644479107277;4022.1151346703678;4172.415861779354;4195.832166713328;4204;312532.62402127974;314677;314880;315180;315317;57036.71481642565;57208.71395643079;57665.42334576601;57702;57744.71127644335;34082.48876266858;59357;59366;59537.70231148817;59726;16101.838981610268;16600;16603.169841507875;16648.75026874598;16651.25069371881;11017.173711971634;11115.499802508906;12065.09511786602;12536.749265014649;12688.17526860751;18619.534511637317;18625.578674772194;18631.44105676832;18638.627227455374;18721;6657.900131498031;6746.865062698718;6907.861842763217;7134.678939447737;7147.249538798455;872.3108744092223;939.6429356844492;1004.7940172264668;1065.5897479470304;1078.0136175399577;30364.392712145633;31013.689863101532;31231.84384078065;31836.522452163237;31857.362852742815;1071.3625392891217;1071.6570697376765;1090.3948308688596;1208.5951206345972;1227.1410012990764;15606.821260658247;16572.834271657128;16693;16693.165341732863;16886.324547018226;483842;487888;488232;488891;492677;54741.726291368286;54862.17706734402;54905.72547137239;54920.45079549233;54958;1204.151073493185;1242.2484396939722;1286.3439645780531;1316.4405127820637;1332.5535945458187;27479.587806182524;31605.36789264248;31831.84084079565;32292.515612266303;32533.511997320533;49737;50788;51100;54821.45178548323;55480.72259638676;459383;460988.37645294337;464785;465072;465450;26845.194644160707;27015.459690806467;27326.180214593594;28280.15159545217;41547.7922610385;44970.550294496636;45192.774036130926;45310;45465.09069818651;45835.54164458313;37425.62574374222;37662.81168594139;38583.614163858;39044.6095539041;39765;37658;37698.81150594229;38136.04659883525;38656.42015369828;39034.804825976826;38662.613373865905;39343;39396.409053863645;39645.80177099096;39754;32884;34308.82845585756;34664;35184.472232916014;35418.64581354154;26475.205743827715;26951.814996776902;27003;27065;27220 15:51:20 INFO - PROCESS | 17885 | |2;jquery.html;369.7319443403531;384.7729839394758;398.2274387687886;401.43798681845414;417.9024663177543;12849.332610944188;15284.312205950731;16771.413000544984;18897.527561810963;19752.111154998023;18493.630127397442;18690.53273668159;19078.42764717057;19089.427317180507;19421.89249720045;5556.638818476807;5664.668802831331;5798.3331916829475;5801.245838041049;5802.97098514509;4661.300804879262;5798.942010579904;5834.912476312858;5839.708014599253;5840.678762668052;5646.378898321182;5793.362730099686;5794.71026448678;5797.623154494965;5801.158831969362;5854.590178687494;5987.251593550806;5998.340182579913;5999.610025348338;6001.549883758714;5657.57568182388;5787.363390027093;5812.070068788974;5815.15680226369;5836.445537673914;12044.156909016372;12068.336241506713;12194.695132621666;12197.08521860864;13268.933655331759;2189.4526368407896;2230.5761905237955;2316.9420764481024;2552.710395303162;2630.9605355919484;9822.607095716163;10038.447885366302;10178.541965611565;10190.082892539633;10676.306040107369;1885.3684015854567;1916.434651777715;1947.0751393088312;1989.8308643765297;1993.1614965535293;79040;80073.59963200205;80890.59554702189;81216.59391703062;83320;10395.428251446243;10596.735081623021;10605.628802991858;10675.145988320915;10924;15132.621684457756;15137.848621513864;15140.318685659171;15146.394144234107;15152.772708409382;7086.858262834818;7308.415326773846;7311.3785328247695;7458.664360103809;7510.549367037996;7085.50401471892;7086.893696594555;7126.109236345457;7408.629568521553;7439.404847612178;4474.865754027384;4605.677602567788;4607.390755639667;4652.232381657056;4733.431988161374;1741.8519425848692;1831.1851226204212;1906.809319068082;1939.33093082889;1976.594798066393;846.8687353460224;917.6467060181744;1015.94920253987;1039.1634734039171;1045.4459136657583;18966;19215.51961200981;19556.315528956704;19572.70640940387;19930.20279188816;956.8947415784259;964.2189826240767;968.9515524223901;972.2902281334665;981.1316984468797;10434;10854.131669466424;10888.40113793749;10936.890631093745;10948.288361256493;459340;460704;468045;470103;473171;85707.5714621423;87058.56470717606;87322.69060447549;89116.5544172275;90731.54634226787;927.5223260021095;960.1550635440767;968.6319198704447;971.4948226921961;976.7948730766476;16599.253033613757;16754.1622918856;17020;17308.22113004943;17603.55991100233;41369.37945930753;42015.579844201166;42179.1564168721;42571.574284258;43362;56705;57094.7145264271;57231.42768572427;58391.66433342619;58428.707856460445;7884.960575197317;8046.114927358103;8142.145074767064;8181.386396020203;8424.494530327955;10126.493675316058;12083.275003499819;12312.445939932546;12782.61652150437;13024.739505209653;10938.835917461403;11002.064824489687;11175.21773475849;11274.323540587591;11469.655910322703;10553.472326383497;10623.628173013907;10756.946215268874;10786.946065269623;10823.296485728402;11831.585894493652;12011.219270747373;12028.939855300667;12085.456154472893;12254.877451225373;8181.100078991425;8267.379946504156;8397.118302578385;8450.704225352083;8694.695685651224;10212.897871021194;11412.885871141183;11463.598774042868;11489.368084755419;12056.276623402624 15:51:20 INFO - PROCESS | 17885 | |3;mootools.html;1415.9221242831643;1467.9266036698166;1566.45957144785;1720.4773775208937;1746.8515176210021;8070.717524886615;8369.121242269566;8501.574921253943;9132.726018219464;9149.588268527916;8608.698695545658;8928.866067008983;8934.106589341063;9096.954515227431;9103.908960910388;2284.8057915077197;2306.7116610423695;2314.351981445198;2315.988420057897;2317.2584772872688;1962.8822270663807;2019.636465436221;2253.7408198057187;2302.1482051640915;2335.486193037529;2221.7556068832414;2265.2864347730497;2298.4139044543617;2314.733805612351;2319.0375993962484;2300.3673989652893;2306.308107567723;2309.457277539777;2358.7759162879497;2368.348704106367;2271.8523295985706;2281.9429514262274;2286.8503606609734;2287.3938406322336;2304.8156147508325;8999.460032398078;9116.453012819253;9163.08369163085;9179.265658747285;9182.26541876655;5141.794328226866;5293.470652934676;5293.523582877559;5297.602679798992;5297.761600727977;3190.4257233697926;3293.4236508610898;3329.8002119872885;3347.4811404232382;3387.2548039431285;1046.1108058150587;1097.8746784545904;1099.4887377369482;1105.7180418993187;1131.705756503307;12659.746805063847;12680.873191268154;12713.42789574462;12739.171953822972;12742.108052436333;4316.827326906951;4338.869833904988;4553.658475614276;4588.036512332447;4600.516945720651;10789.352638841538;10799.298045627009;10799.352038877692;10804.08165305942;10810.189235807346;5303.867403314871;5313.415524292324;5326.92009619856;5327.493888080626;5332.760025798849;4673.696209746356;4752.049590081998;4798.66409351349;4808.7595620219545;4814.325994360793;3978.5822488638855;3992.640662340345;4035.4955630546183;4096.5903409658795;4119.237940980928;1109.2457129152253;1146.4325159046325;1185.3421351150023;1280.9039322050883;1288.761579107868;838.4340570115148;868.1492137705085;868.6438560190292;896.5965315608003;970.1317320997667;5726.828195154152;5744.138379243147;5770.163326317745;5939.881202376015;6244.937550624527;926.0878035135386;927.0821886332483;927.2151109114927;938.8497840345512;968.2834702320232;3952.5256969163884;4207.8316867325575;4218.0720241546815;4224.598663126998;4319.3952846601505;11108.722281942855;11331.376774277329;11414.543418263342;11451.198416110792;11467.541298347973;11366.602168924048;11367.261128026652;11371.943140284246;11392.601258956063;11398.373089479992;7961.203879611992;8264.504129752235;8268.751937441886;8411.327093832479;8429.241368276884;5745.454181852718;6149.156777014366;6223.688815559113;6282.089097080806;6365.554411191172;9918.603255869684;10090.94954525252;10151.137153342048;10168.949155254177;10226.488675566043;10820.350778953289;10831.404272765074;10865.402402867918;10867.565297388015;10873.673789786319;8184.672613095409;8186.54973976437;8273.089960104504;8274.627641756257;8283.917160828314;8693.087225841196;8719.782005449915;8794.868076978979;8797.252233560219;8798.47209167452;7860.606969651609;8027.1972802719265;8027.799305017188;8073.273405393543;8237.299829514559;7744.7676569703;7802.453828231969;7818.3354414875375;7853.607319634109;8161.183881611792;6958.338957799001;6958.721651133898;7025.297470252934;7029.683664235019;7039.753608623673;5058.418281897629;5080.542751152414;5121.103806833715;5133.255677926754;5352.839414817562;5208.010478009166;5274.736263186902;5338.039152952506;5338.306020217502;5349.705766182898 15:51:20 INFO - PROCESS | 17885 | |4;prototype.html;324.63640722390926;331.17537332043213;335.92273777031284;341.26627750336786;342.28462513347097;5280.762365693543;5378.897326048151;5380.623356365056;5384.892302153955;5428.294321738178;5278.6568873023325;5307.946920530793;5352.705601191933;5367;5374.4894235047705;3941.7634941903434;4082.4284600156;4111.897202569945;4200.285951388271;4211.115665710205;4215.3255479123345;4217.40956266123;4235.915281694364;4249.235137675218;4255.808488618004;4187.455630767996;4187.602177793105;4191.643710284629;4217.114405974749;4235.2800023996;3917.4319723640056;4049.959500404988;4063.553009168989;4138.793060347;4207.558206388331;3714.814259287025;3729.1236559408517;3729.3287208302495;3736.663700266989;3742.3076730804887;5262;5263.710390954241;5274.26160337553;5275.709835959021;5280.970210808894;4711.316859055418;4747.382840230783;4751.572358487718;4777.092352453023;4865.805367785284;5578.386377498472;5581.57669794204;5584.944150558483;5588.273524441837;5606.495415412633;4069.450624165759;4080.3879418087527;4088.8329185531716;4090.6318431341024;4098.426220329126;5525.336959564837;5778.826635200909;5812.418758124154;5823.737931793079;5854.121881717776;4062.979685101615;4078.4290199372113;4082.775447350424;4106.630403263721;4116.876493705194;4139.19285739278;4140;4149.647279981175;4150.771707556053;4155.667546596265;4531.909361812811;4716.6698331116495;4724.598409135193;4803.639727020487;4804.73573953429;5587.413321601173;5597.328320601554;5603.299587551556;5605.215269862222;5609.5792815539;1405.7118290750373;1425.5224499792478;1426.493594773861;1441.8825410307095;1444.9349779259958;918.1461241045758;927.784602171158;935.822193783179;936.513013233115;975.8975307592742;5196.298499702568;5247.763850626731;5309.442508536626;5358.2498450217;5478.753456094485;5839.153322768175;5849.239598852122;5863.237779088777;5866.882662346815;5892.234009578728;5225.947740522546;5363.03465376236;5458.972705136528;5474.507294343449;5491.533219676372;2991.7307442330293;3030.1515575638855;3037.453258413507;3056.006797790727;3069.4474994501206;5941.37615550361;5951.1073338999495;5971.954907891101;5981.641101533922;5985.521158307412;5685.772569097273;5799.217105690762;5817.650940943557;5839.766409343579;5850;5853.619514731528;5856.297244330707;5897.879402913432;5908.497777688945;5929.584929054925;5507.697076660662;5705.800296989585;5772.7402266898935;5783.566232532493;5784;5629.789595237044;5643.294588176478;5648.265725455664;5652.745626446902;5660;5873.588848780544;5891.351951285269;5896.616719913192;5917.644941303535;5918.556108291981;4084.509858816961;4088.9591104088577;4177.642266263538;4200.201961627281;4209.368594710818;5620.803271885465;5623.325200975745;5660;5673.432656734293;5708.343540492897;5494.118056003752;5639.8590035249445;5641.407652196624;5658.830235092953;5664.773409063591;5479.671219726829;5525.640833345819;5532.170174473861;5532.585056120887;5546.334439867081;5435.619506634655;5473.589480788877;5503.944960550343;5514.373259888423;5540.612157149122;5364.624476286623;5401.756920938488;5491.890162196813;5549.195366671729;5632.464915832989;5447.836564903059;5450.454954504517;5460.5904557159165;5467.5625949923115;5472.480114389126 15:51:20 INFO - PROCESS | 17885 | |5;yui.html;738.4277185181484;738.8263758016868;769.5728870476883;770.0989841884993;802.9919700802992;15523.844761552382;16248.75626865598;16385.508434746946;16470.67058658826;16484.835151648465;15272.083674979482;15351.92324038381;15394.692106157898;15398.769018464733;15401.460948866792;669.5480550628306;675.3010633993817;768.8154842837735;779.1390513482605;789.5578476053399;804.4489524675587;804.6258489802241;806.891069705591;807.5033854179667;810.266708628689;861.1044513705739;862.6851199312268;863.0377129500594;863.1066845814584;863.2662237098448;706.9045678833395;853.9274161696263;857.6998050682274;857.8069934264772;857.8927634045745;525.0417987173424;603.7011679218815;610.5115907274163;613.9938600613981;625.7278084033414;14778.334974926154;14860;14888.925555372261;14902.552923412315;15065.246737663074;13595.388207530685;14193.361298741478;14566.781498277534;14651.069786042728;14806.777898331533;11837.289762614271;11935.880641193564;12108.273503589813;12251;12692.17500862441;4227.788610569459;4284.892877678052;4322.632576231024;4521.660875434355;4524.86425407235;75785.86321205186;76345.61827190829;78493.60753196255;78593.60703196505;80912;11084.722881928017;11263.324200547993;11300.491477883515;11440.256383335056;11900.88099119015;22192.778072219393;22246.221382251544;22252.332430027123;22279.888600556893;22357.329280121623;8189.467684600482;8239.176082391712;8463.576821158917;8524.531150786766;8578.013528444184;4394.648428125743;4452.243118669834;4463.66522510813;4536.750478723636;4538.773061346919;3380.797152170878;3498.160441494074;3510.052285882797;3512.5609298837644;3517.103138699615;555.9444055594489;565.1889538512248;566.8554518597814;566.869619987397;567.821136342051;544.9209864569615;644.3974883483958;656.5896314803248;663.5587334422555;680.8876535371708;8706.172913573328;8972.147645973622;9022.548872556346;9135.680251191176;9314.86027709585;585.4262822434039;602.6263716495713;644.9226092868886;665.9200895892524;670.2794495916909;4238.745675259494;4419.18245124653;4426.734395936254;4531.093781243699;4570.565796249351;114142;114641;121179;122011;123816.38091809483;59312;82532;96403.51798240964;97314.51342743241;97381;64285;92109.53945230231;92827;94311.52844235735;94780;8395.454295470774;8608.182222689085;8640.611172497127;8646.351523635627;8804.911950880409;53519;54168.45831541792;54681.72659136679;55392.72303638456;55718;71231.28768712247;78612;78731;79822.20177798148;79954.60022699849;12099.818502722643;12139.453724582238;12312.87687123153;12358.876411236131;12358.938205308916;12302.75394492123;12597.370131493206;12924.806127908278;13501.864981350061;13641.454341826216;10768.030877221088;11115.277506962022;11219;11296.49165787525;11634;10758;11008.339499630049;11016.779664406828;11550.249233800112;11665.183437159318;10041.497925103862;10175.135113515138;10479.94760026195;10635.734106647396;10888.29226100301;8681.435706679045;8749.47503149813;8914.153155450222;9173.35786494939;9409.106134917172;8638.524881131598;8839.779005524657;8964;9074.546272686472;9098.909010909807 15:51:20 INFO - PROCESS | 17885 | __end_tp_report 15:51:20 INFO - PROCESS | 17885 | __start_cc_report 15:51:20 INFO - PROCESS | 17885 | _x_x_mozilla_cycle_collect,1894 15:51:20 INFO - PROCESS | 17885 | __end_cc_report 15:51:20 INFO - PROCESS | 17885 | __startTimestamp1477263080631__endTimestamp 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | ------- Summary: start ------- 15:51:20 INFO - PROCESS | 17885 | Number of tests: 6 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#0] dojo.html Cycles:165 Average:5344.80 Median:5538.22 stddev:592.31 (10.7%) stddev-sans-first:566.00 15:51:20 INFO - PROCESS | 17885 | Values: 3045.8 3057.9 3105.4 3121.6 3122.5 5111.2 5165.9 5289.8 5356.2 5418.9 5243.6 5268.4 5388.5 5397.6 5468.1 4905.5 4930.5 4977.3 4984.6 5013.4 4944.0 5028.5 5047.5 5049.9 5100.6 4803.5 4990.8 4994.1 5025.5 5107.9 4847.2 4911.3 4917.8 4971.9 4997.3 4877.8 4895.8 4899.8 4900.0 4961.6 5410.6 5419.8 5423.5 5440.9 5479.2 5573.1 5586.6 5616.1 5616.8 5743.9 5461.6 5537.5 5551.2 5707.2 5720.2 5323.0 5324.4 5325.3 5328.8 5333.7 5673.0 5677.2 5790.9 5793.8 5868.5 5601.6 5650.4 5689.2 5693.5 5779.1 5722.5 5778.9 5782.1 5784.2 5787.0 5766.5 5776.1 5788.0 5789.9 5876.8 5446.5 5548.8 5561.9 5636.7 5636.9 4364.1 4396.7 4409.7 4453.5 4465.8 3913.4 3921.0 3923.0 3960.3 3971.2 5430.1 5436.1 5436.6 5437.4 5439.0 5583.6 5708.7 5780.9 5846.7 5856.4 5457.3 5491.1 5493.8 5503.1 5549.1 5031.1 5031.8 5037.0 5040.2 5095.7 5775.1 5817.9 5882.2 5950.4 6012.8 5806.6 5818.8 5820.6 5858.9 5876.4 5892.8 5934.4 5939.8 5943.6 5958.0 5478.7 5538.2 5647.6 5653.5 5655.7 5758.3 5761.9 5816.7 5862.9 5881.5 5697.1 5698.2 5699.6 5705.9 5706.2 5478.0 5545.5 5668.6 5669.5 5675.5 5532.0 5586.7 5599.2 5602.8 5606.6 5630.7 5651.9 5654.3 5659.3 5664.9 5513.2 5533.1 5605.1 5614.3 5616.6 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#1] ext.html Cycles:180 Average:58104.22 Median:20568.42 stddev:113581.34 (552.2%) stddev-sans-first:113817.71 15:51:20 INFO - PROCESS | 17885 | Values: 531.3 583.5 600.8 617.6 633.6 33586.7 42569.0 43526.0 43639.1 46861.3 43417.1 43830.6 43834.1 43849.6 43909.3 8100.8 8280.1 8896.6 9054.5 9062.0 10440.1 10714.5 10722.1 11051.1 11061.9 10889.1 10985.3 10993.6 10997.4 11000.0 8074.4 8262.4 10489.2 10699.3 10730.8 10453.1 10519.9 10582.1 10588.5 10588.9 41121.6 41552.6 41561.0 41572.8 41604.4 3370.5 3422.2 3685.8 3711.2 4097.1 30447.8 30692.0 31233.0 31252.0 31317.0 3959.6 4022.1 4172.4 4195.8 4204.0 312532.6 314677.0 314880.0 315180.0 315317.0 57036.7 57208.7 57665.4 57702.0 57744.7 34082.5 59357.0 59366.0 59537.7 59726.0 16101.8 16600.0 16603.2 16648.8 16651.3 11017.2 11115.5 12065.1 12536.7 12688.2 18619.5 18625.6 18631.4 18638.6 18721.0 6657.9 6746.9 6907.9 7134.7 7147.2 872.3 939.6 1004.8 1065.6 1078.0 30364.4 31013.7 31231.8 31836.5 31857.4 1071.4 1071.7 1090.4 1208.6 1227.1 15606.8 16572.8 16693.0 16693.2 16886.3 483842.0 487888.0 488232.0 488891.0 492677.0 54741.7 54862.2 54905.7 54920.5 54958.0 1204.2 1242.2 1286.3 1316.4 1332.6 27479.6 31605.4 31831.8 32292.5 32533.5 49737.0 50788.0 51100.0 54821.5 55480.7 459383.0 460988.4 464785.0 465072.0 465450.0 26845.2 27015.5 27326.2 28280.2 41547.8 44970.6 45192.8 45310.0 45465.1 45835.5 37425.6 37662.8 38583.6 39044.6 39765.0 37658.0 37698.8 38136.0 38656.4 39034.8 38662.6 39343.0 39396.4 39645.8 39754.0 32884.0 34308.8 34664.0 35184.5 35418.6 26475.2 26951.8 27003.0 27065.0 27220.0 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#2] jquery.html Cycles:180 Average:27714.20 Median:20885.41 stddev:77011.72 (368.7%) stddev-sans-first:77200.38 15:51:20 INFO - PROCESS | 17885 | Values: 369.7 384.8 398.2 401.4 417.9 12849.3 15284.3 16771.4 18897.5 19752.1 18493.6 18690.5 19078.4 19089.4 19421.9 5556.6 5664.7 5798.3 5801.2 5803.0 4661.3 5798.9 5834.9 5839.7 5840.7 5646.4 5793.4 5794.7 5797.6 5801.2 5854.6 5987.3 5998.3 5999.6 6001.5 5657.6 5787.4 5812.1 5815.2 5836.4 12044.2 12068.3 12194.7 12197.1 13268.9 2189.5 2230.6 2316.9 2552.7 2631.0 9822.6 10038.4 10178.5 10190.1 10676.3 1885.4 1916.4 1947.1 1989.8 1993.2 79040.0 80073.6 80890.6 81216.6 83320.0 10395.4 10596.7 10605.6 10675.1 10924.0 15132.6 15137.8 15140.3 15146.4 15152.8 7086.9 7308.4 7311.4 7458.7 7510.5 7085.5 7086.9 7126.1 7408.6 7439.4 4474.9 4605.7 4607.4 4652.2 4733.4 1741.9 1831.2 1906.8 1939.3 1976.6 846.9 917.6 1015.9 1039.2 1045.4 18966.0 19215.5 19556.3 19572.7 19930.2 956.9 964.2 969.0 972.3 981.1 10434.0 10854.1 10888.4 10936.9 10948.3 459340.0 460704.0 468045.0 470103.0 473171.0 85707.6 87058.6 87322.7 89116.6 90731.5 927.5 960.2 968.6 971.5 976.8 16599.3 16754.2 17020.0 17308.2 17603.6 41369.4 42015.6 42179.2 42571.6 43362.0 56705.0 57094.7 57231.4 58391.7 58428.7 7885.0 8046.1 8142.1 8181.4 8424.5 10126.5 12083.3 12312.4 12782.6 13024.7 10938.8 11002.1 11175.2 11274.3 11469.7 10553.5 10623.6 10756.9 10786.9 10823.3 11831.6 12011.2 12028.9 12085.5 12254.9 8181.1 8267.4 8397.1 8450.7 8694.7 10212.9 11412.9 11463.6 11489.4 12056.3 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#3] mootools.html Cycles:180 Average:5918.22 Median:4775.36 stddev:3450.93 (72.3%) stddev-sans-first:3444.03 15:51:20 INFO - PROCESS | 17885 | Values: 1415.9 1467.9 1566.5 1720.5 1746.9 8070.7 8369.1 8501.6 9132.7 9149.6 8608.7 8928.9 8934.1 9097.0 9103.9 2284.8 2306.7 2314.4 2316.0 2317.3 1962.9 2019.6 2253.7 2302.1 2335.5 2221.8 2265.3 2298.4 2314.7 2319.0 2300.4 2306.3 2309.5 2358.8 2368.3 2271.9 2281.9 2286.9 2287.4 2304.8 8999.5 9116.5 9163.1 9179.3 9182.3 5141.8 5293.5 5293.5 5297.6 5297.8 3190.4 3293.4 3329.8 3347.5 3387.3 1046.1 1097.9 1099.5 1105.7 1131.7 12659.7 12680.9 12713.4 12739.2 12742.1 4316.8 4338.9 4553.7 4588.0 4600.5 10789.4 10799.3 10799.4 10804.1 10810.2 5303.9 5313.4 5326.9 5327.5 5332.8 4673.7 4752.0 4798.7 4808.8 4814.3 3978.6 3992.6 4035.5 4096.6 4119.2 1109.2 1146.4 1185.3 1280.9 1288.8 838.4 868.1 868.6 896.6 970.1 5726.8 5744.1 5770.2 5939.9 6244.9 926.1 927.1 927.2 938.8 968.3 3952.5 4207.8 4218.1 4224.6 4319.4 11108.7 11331.4 11414.5 11451.2 11467.5 11366.6 11367.3 11371.9 11392.6 11398.4 7961.2 8264.5 8268.8 8411.3 8429.2 5745.5 6149.2 6223.7 6282.1 6365.6 9918.6 10090.9 10151.1 10168.9 10226.5 10820.4 10831.4 10865.4 10867.6 10873.7 8184.7 8186.5 8273.1 8274.6 8283.9 8693.1 8719.8 8794.9 8797.3 8798.5 7860.6 8027.2 8027.8 8073.3 8237.3 7744.8 7802.5 7818.3 7853.6 8161.2 6958.3 6958.7 7025.3 7029.7 7039.8 5058.4 5080.5 5121.1 5133.3 5352.8 5208.0 5274.7 5338.0 5338.3 5349.7 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#4] prototype.html Cycles:180 Average:4714.34 Median:5393.32 stddev:1380.69 (25.6%) stddev-sans-first:1344.67 15:51:20 INFO - PROCESS | 17885 | Values: 324.6 331.2 335.9 341.3 342.3 5280.8 5378.9 5380.6 5384.9 5428.3 5278.7 5307.9 5352.7 5367.0 5374.5 3941.8 4082.4 4111.9 4200.3 4211.1 4215.3 4217.4 4235.9 4249.2 4255.8 4187.5 4187.6 4191.6 4217.1 4235.3 3917.4 4050.0 4063.6 4138.8 4207.6 3714.8 3729.1 3729.3 3736.7 3742.3 5262.0 5263.7 5274.3 5275.7 5281.0 4711.3 4747.4 4751.6 4777.1 4865.8 5578.4 5581.6 5584.9 5588.3 5606.5 4069.5 4080.4 4088.8 4090.6 4098.4 5525.3 5778.8 5812.4 5823.7 5854.1 4063.0 4078.4 4082.8 4106.6 4116.9 4139.2 4140.0 4149.6 4150.8 4155.7 4531.9 4716.7 4724.6 4803.6 4804.7 5587.4 5597.3 5603.3 5605.2 5609.6 1405.7 1425.5 1426.5 1441.9 1444.9 918.1 927.8 935.8 936.5 975.9 5196.3 5247.8 5309.4 5358.2 5478.8 5839.2 5849.2 5863.2 5866.9 5892.2 5225.9 5363.0 5459.0 5474.5 5491.5 2991.7 3030.2 3037.5 3056.0 3069.4 5941.4 5951.1 5972.0 5981.6 5985.5 5685.8 5799.2 5817.7 5839.8 5850.0 5853.6 5856.3 5897.9 5908.5 5929.6 5507.7 5705.8 5772.7 5783.6 5784.0 5629.8 5643.3 5648.3 5652.7 5660.0 5873.6 5891.4 5896.6 5917.6 5918.6 4084.5 4089.0 4177.6 4200.2 4209.4 5620.8 5623.3 5660.0 5673.4 5708.3 5494.1 5639.9 5641.4 5658.8 5664.8 5479.7 5525.6 5532.2 5532.6 5546.3 5435.6 5473.6 5503.9 5514.4 5540.6 5364.6 5401.8 5491.9 5549.2 5632.5 5447.8 5450.5 5460.6 5467.6 5472.5 15:51:20 INFO - PROCESS | 17885 | 15:51:20 INFO - PROCESS | 17885 | [#5] yui.html Cycles:180 Average:20437.13 Median:27974.33 stddev:30216.50 (108.0%) stddev-sans-first:30265.07 15:51:20 INFO - PROCESS | 17885 | Values: 738.4 738.8 769.6 770.1 803.0 15523.8 16248.8 16385.5 16470.7 16484.8 15272.1 15351.9 15394.7 15398.8 15401.5 669.5 675.3 768.8 779.1 789.6 804.4 804.6 806.9 807.5 810.3 861.1 862.7 863.0 863.1 863.3 706.9 853.9 857.7 857.8 857.9 525.0 603.7 610.5 614.0 625.7 14778.3 14860.0 14888.9 14902.6 15065.2 13595.4 14193.4 14566.8 14651.1 14806.8 11837.3 11935.9 12108.3 12251.0 12692.2 4227.8 4284.9 4322.6 4521.7 4524.9 75785.9 76345.6 78493.6 78593.6 80912.0 11084.7 11263.3 11300.5 11440.3 11900.9 22192.8 22246.2 22252.3 22279.9 22357.3 8189.5 8239.2 8463.6 8524.5 8578.0 4394.6 4452.2 4463.7 4536.8 4538.8 3380.8 3498.2 3510.1 3512.6 3517.1 555.9 565.2 566.9 566.9 567.8 544.9 644.4 656.6 663.6 680.9 8706.2 8972.1 9022.5 9135.7 9314.9 585.4 602.6 644.9 665.9 670.3 4238.7 4419.2 4426.7 4531.1 4570.6 114142.0 114641.0 121179.0 122011.0 123816.4 59312.0 82532.0 96403.5 97314.5 97381.0 64285.0 92109.5 92827.0 94311.5 94780.0 8395.5 8608.2 8640.6 8646.4 8804.9 53519.0 54168.5 54681.7 55392.7 55718.0 71231.3 78612.0 78731.0 79822.2 79954.6 12099.8 12139.5 12312.9 12358.9 12358.9 12302.8 12597.4 12924.8 13501.9 13641.5 10768.0 11115.3 11219.0 11296.5 11634.0 10758.0 11008.3 11016.8 11550.2 11665.2 10041.5 10175.1 10479.9 10635.7 10888.3 8681.4 8749.5 8914.2 9173.4 9409.1 8638.5 8839.8 8964.0 9074.5 9098.9 15:51:20 INFO - PROCESS | 17885 | -------- Summary: end -------- 15:51:20 INFO - PROCESS | 17885 | 15:51:21 INFO - TEST-INFO | 17885: exit 0 15:51:21 INFO - TEST-OK | dromaeo_css | took 1118562ms 15:51:21 INFO - TEST-START | kraken 15:51:21 INFO - operating with platform_type : linux_ 15:51:21 INFO - Initialising browser for kraken test... 15:51:21 INFO - TEST-INFO | started process 18193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpicPdFH/profile http://localhost:37764/getInfo.html) 15:51:25 INFO - PROCESS | 18193 | __metrics Screen width/height:1600/1200 15:51:25 INFO - PROCESS | 18193 | colorDepth:24 15:51:25 INFO - PROCESS | 18193 | Browser inner width/height: 1024/697 15:51:25 INFO - PROCESS | 18193 | __metrics 15:51:26 INFO - PROCESS | 18193 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:51:26 INFO - PROCESS | 18193 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:51:27 INFO - PROCESS | 18193 | console.error: PushService: 15:51:27 INFO - PROCESS | 18193 | stateChangeProcessEnqueue: Error transitioning state 15:51:27 INFO - PROCESS | 18193 | UnknownError 15:51:27 INFO - TEST-INFO | 18193: exit 0 15:51:27 INFO - Browser initialized. 15:51:27 INFO - Running cycle 1/1 for kraken test... 15:51:27 INFO - TEST-INFO | started process 18276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpicPdFH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:39 INFO - PROCESS | 18276 | RSS: Main: 172281856 15:51:39 INFO - PROCESS | 18276 | 15:51:42 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37764/tests/kraken/driver.html?testName=audio-beat-detection) 15:51:43 INFO - PROCESS | 18276 | RSS: Main: 229269504 15:51:43 INFO - PROCESS | 18276 | 15:51:45 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37764/tests/kraken/driver.html?testName=audio-dft) 15:51:46 INFO - PROCESS | 18276 | RSS: Main: 216068096 15:51:46 INFO - PROCESS | 18276 | 15:51:49 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37764/tests/kraken/driver.html?testName=audio-fft) 15:51:50 INFO - PROCESS | 18276 | RSS: Main: 297504768 15:51:50 INFO - PROCESS | 18276 | 15:51:52 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37764/tests/kraken/driver.html?testName=audio-oscillator) 15:51:52 INFO - PROCESS | 18276 | RSS: Main: 209104896 15:51:52 INFO - PROCESS | 18276 | 15:51:55 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37764/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:51:55 INFO - PROCESS | 18276 | RSS: Main: 211341312 15:51:55 INFO - PROCESS | 18276 | 15:51:58 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37764/tests/kraken/driver.html?testName=imaging-darkroom) 15:51:59 INFO - PROCESS | 18276 | RSS: Main: 248332288 15:51:59 INFO - PROCESS | 18276 | 15:52:02 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37764/tests/kraken/driver.html?testName=imaging-desaturate) 15:52:03 INFO - PROCESS | 18276 | RSS: Main: 247193600 15:52:03 INFO - PROCESS | 18276 | 15:52:05 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37764/tests/kraken/driver.html?testName=json-parse-financial) 15:52:06 INFO - PROCESS | 18276 | RSS: Main: 246112256 15:52:06 INFO - PROCESS | 18276 | 15:52:07 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37764/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:08 INFO - PROCESS | 18276 | RSS: Main: 209223680 15:52:08 INFO - PROCESS | 18276 | 15:52:10 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:10 INFO - PROCESS | 18276 | RSS: Main: 215961600 15:52:10 INFO - PROCESS | 18276 | 15:52:12 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:13 INFO - PROCESS | 18276 | RSS: Main: 226103296 15:52:13 INFO - PROCESS | 18276 | 15:52:16 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:16 INFO - PROCESS | 18276 | RSS: Main: 234110976 15:52:16 INFO - PROCESS | 18276 | 15:52:19 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:52:20 INFO - PROCESS | 18276 | RSS: Main: 231632896 15:52:20 INFO - PROCESS | 18276 | 15:52:21 INFO - PROCESS | 18276 | Cycle 1(1): loaded http://localhost:37764/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37764/tests/kraken/driver.html?testName=ai-astar) 15:52:22 INFO - PROCESS | 18276 | RSS: Main: 281542656 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | __start_tp_report 15:52:22 INFO - PROCESS | 18276 | _x_x_mozilla_page_load 15:52:22 INFO - PROCESS | 18276 | _x_x_mozilla_page_load_details 15:52:22 INFO - PROCESS | 18276 | |i|pagename|runs| 15:52:22 INFO - PROCESS | 18276 | |0;ai-astar;103;111;111;110;113;112;111;110;112;110 15:52:22 INFO - PROCESS | 18276 | |1;audio-beat-detection;146;123;125;159;122;125;121;125;161;124 15:52:22 INFO - PROCESS | 18276 | |2;audio-dft;161;162;161;162;160;161;163;162;161;162 15:52:22 INFO - PROCESS | 18276 | |3;audio-fft;75;75;75;105;76;76;75;86;98;77 15:52:22 INFO - PROCESS | 18276 | |4;audio-oscillator;82;82;128;82;83;81;102;82;82;122 15:52:22 INFO - PROCESS | 18276 | |5;imaging-gaussian-blur;117;117;117;117;116;117;116;117;117;117 15:52:22 INFO - PROCESS | 18276 | |6;imaging-darkroom;147;146;147;145;145;146;147;146;146;146 15:52:22 INFO - PROCESS | 18276 | |7;imaging-desaturate;84;84;85;84;85;84;84;84;85;85 15:52:22 INFO - PROCESS | 18276 | |8;json-parse-financial;54;52;53;52;53;54;53;54;53;54 15:52:22 INFO - PROCESS | 18276 | |9;json-stringify-tinderbox;60;60;59;60;61;60;60;60;60;60 15:52:22 INFO - PROCESS | 18276 | |10;stanford-crypto-aes;81;81;90;84;89;84;81;80;83;82 15:52:22 INFO - PROCESS | 18276 | |11;stanford-crypto-ccm;145;141;138;135;144;134;138;128;171;131 15:52:22 INFO - PROCESS | 18276 | |12;stanford-crypto-pbkdf2;178;170;169;172;172;170;171;171;168;170 15:52:22 INFO - PROCESS | 18276 | |13;stanford-crypto-sha256-iterative;67;66;67;66;66;70;65;67;64;67 15:52:22 INFO - PROCESS | 18276 | __end_tp_report 15:52:22 INFO - PROCESS | 18276 | __start_cc_report 15:52:22 INFO - PROCESS | 18276 | _x_x_mozilla_cycle_collect,1675 15:52:22 INFO - PROCESS | 18276 | __end_cc_report 15:52:22 INFO - PROCESS | 18276 | __startTimestamp1477263142220__endTimestamp 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | ------- Summary: start ------- 15:52:22 INFO - PROCESS | 18276 | Number of tests: 14 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.75 (2.5%) stddev-sans-first:1.05 15:52:22 INFO - PROCESS | 18276 | Values: 103.0 111.0 111.0 110.0 113.0 112.0 111.0 110.0 112.0 110.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:125.00 stddev:15.87 (12.7%) stddev-sans-first:16.13 15:52:22 INFO - PROCESS | 18276 | Values: 146.0 123.0 125.0 159.0 122.0 125.0 121.0 125.0 161.0 124.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 15:52:22 INFO - PROCESS | 18276 | Values: 161.0 162.0 161.0 162.0 160.0 161.0 163.0 162.0 161.0 162.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:11.02 (14.5%) stddev-sans-first:11.41 15:52:22 INFO - PROCESS | 18276 | Values: 75.0 75.0 75.0 105.0 76.0 76.0 75.0 86.0 98.0 77.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:82.00 stddev:18.24 (22.2%) stddev-sans-first:18.94 15:52:22 INFO - PROCESS | 18276 | Values: 82.0 82.0 128.0 82.0 83.0 81.0 102.0 82.0 82.0 122.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:52:22 INFO - PROCESS | 18276 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 15:52:22 INFO - PROCESS | 18276 | Values: 147.0 146.0 147.0 145.0 145.0 146.0 147.0 146.0 146.0 146.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 15:52:22 INFO - PROCESS | 18276 | Values: 84.0 84.0 85.0 84.0 85.0 84.0 84.0 84.0 85.0 85.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:0.79 (1.5%) stddev-sans-first:0.78 15:52:22 INFO - PROCESS | 18276 | Values: 54.0 52.0 53.0 52.0 53.0 54.0 53.0 54.0 53.0 54.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 15:52:22 INFO - PROCESS | 18276 | Values: 60.0 60.0 59.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:3.44 (4.1%) stddev-sans-first:3.53 15:52:22 INFO - PROCESS | 18276 | Values: 81.0 81.0 90.0 84.0 89.0 84.0 81.0 80.0 83.0 82.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:139.50 stddev:11.99 (8.6%) stddev-sans-first:12.61 15:52:22 INFO - PROCESS | 18276 | Values: 145.0 141.0 138.0 135.0 144.0 134.0 138.0 128.0 171.0 131.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.00 stddev:2.73 (1.6%) stddev-sans-first:1.32 15:52:22 INFO - PROCESS | 18276 | Values: 178.0 170.0 169.0 172.0 172.0 170.0 171.0 171.0 168.0 170.0 15:52:22 INFO - PROCESS | 18276 | 15:52:22 INFO - PROCESS | 18276 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.58 (2.4%) stddev-sans-first:1.67 15:52:22 INFO - PROCESS | 18276 | Values: 67.0 66.0 67.0 66.0 66.0 70.0 65.0 67.0 64.0 67.0 15:52:22 INFO - PROCESS | 18276 | -------- Summary: end -------- 15:52:22 INFO - PROCESS | 18276 | 15:52:23 INFO - TEST-INFO | 18276: exit 0 15:52:23 INFO - TEST-OK | kraken | took 61832ms 15:52:23 INFO - SUITE-END | took 1180s 15:52:23 INFO - Completed test suite (00:19:40) 15:52:23 INFO - javascript benchmark 15:52:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3045.817250964942, 3057.8929737459193, 3105.378924215157, 3121.578586890769, 3122.45357062514, 5111.182210846266, 5165.896682066356, 5289.814856480024, 5356.223347614594, 5418.864528386793, 5243.554297884684, 5268.420473747894, 5388.541973932211, 5397.649152805075, 5468.125099984003, 4905.460399356068, 4930.482299358569, 4977.303177555145, 4984.551390374865, 5013.423456302536, 4944.0, 5028.522290382426, 5047.545720885119, 5049.949500504985, 5100.617453690969, 4803.471618122004, 4990.800367985276, 4994.05113028527, 5025.497450254964, 5107.897842043157, 4847.175980083394, 4911.312416261726, 4917.754112294371, 4971.850844474671, 4997.250412438127, 4877.804887804484, 4895.84675940155, 4899.7550122494085, 4899.995500922302, 4961.627877909171, 5410.594205434608, 5419.83740487786, 5423.457654234585, 5440.863978400532, 5479.232907392969, 5573.136163894603, 5586.581006424534, 5616.1014237721765, 5616.803411880605, 5743.8564035899435, 5461.644993075437, 5537.501624853782, 5551.2228288039705, 5707.200991861101, 5720.170575266563, 5322.973385133088, 5324.41431442541, 5325.281087053276, 5328.840134795923, 5333.679979201261, 5673.035583950738, 5677.233573467612, 5790.94209057904, 5793.768249270066, 5868.501177399969, 5601.551875849922, 5650.378458369576, 5689.231953686282, 5693.5445164387575, 5779.075347944393, 5722.513586345124, 5778.88442231153, 5782.132680098019, 5784.248047753765, 5786.971065144731, 5766.538676905838, 5776.133579963039, 5788.0449725795615, 5789.88420231593, 5876.764929402776, 5446.455354464521, 5548.750306236148, 5561.944380556223, 5636.746346414421, 5636.943630563724, 4364.10535840152, 4396.692231543761, 4409.691321607456, 4453.532379100213, 4465.799039043251, 3913.4325522799036, 3921.0, 3922.9607703923166, 3960.3069462843887, 3971.245463361952, 5430.0768869292315, 5436.130219164994, 5436.592255580847, 5437.429069947678, 5439.048166570834, 5583.581231407579, 5708.71456427177, 5780.855478613068, 5846.736896839652, 5856.385079566584, 5457.345118585716, 5491.121420572689, 5493.807716729895, 5503.091989821634, 5549.139883318092, 5031.069252188357, 5031.77357018935, 5037.01778153264, 5040.168372218543, 5095.694258344512, 5775.104858746901, 5817.941820581825, 5882.205902203148, 5950.404959504015, 6012.759489620454, 5806.5645076618575, 5818.76724931015, 5820.592558520862, 5858.853528661817, 5876.3536011037895, 5892.823215303548, 5934.406559344031, 5939.821805345847, 5943.613665111754, 5958.0, 5478.698671573102, 5538.224648549287, 5647.632903861236, 5653.491185793298, 5655.717214139194, 5758.3090029196765, 5761.884762304814, 5816.680082595498, 5862.94137058624, 5881.470667639933, 5697.116946873241, 5698.230738850202, 5699.5725320600295, 5705.942940570541, 5706.2011318414625, 5478.0, 5545.473180047889, 5668.631538949955, 5669.489745922887, 5675.460831221028, 5531.9723401382735, 5586.692731899784, 5599.244102046172, 5602.775888964396, 5606.607537472501, 5630.74661640219, 5651.915221271602, 5654.293213348332, 5659.26429564154, 5664.886702265849, 5513.173024046425, 5533.059379905345, 5605.075162598126, 5614.29821272341, 5616.550675945833], "value": 5305.339319368215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.2521393852262, 583.5360888093966, 600.8467840700622, 617.576959782549, 633.5944995203074, 33586.66413335866, 42569.0, 43526.0, 43639.12721745567, 46861.297080543736, 43417.13165736691, 43830.56169438305, 43834.12331753363, 43849.56150438495, 43909.341359879625, 8100.756977290691, 8280.089190189074, 8896.555172241378, 9054.547272636375, 9061.95469022654, 10440.112590429822, 10714.464276786168, 10722.0886224671, 11051.115910727123, 11061.944690276538, 10889.074428673572, 10985.285956412847, 10993.61522346718, 10997.395143267117, 11000.0, 8074.394420418463, 8262.380321475914, 10489.213309001856, 10699.251052426333, 10730.839037414446, 10453.11148552374, 10519.894801052045, 10582.100521455686, 10588.470576471145, 10588.94705526475, 41121.58878411208, 41552.58447415518, 41561.0, 41572.79213603913, 41604.37593436101, 3370.5449764281752, 3422.212891035051, 3685.7972811495356, 3711.2206436648066, 4097.078157414588, 30447.847760761055, 30692.0, 31233.0, 31252.0, 31317.0, 3959.5644479107277, 4022.1151346703678, 4172.415861779354, 4195.832166713328, 4204.0, 312532.62402127974, 314677.0, 314880.0, 315180.0, 315317.0, 57036.71481642565, 57208.71395643079, 57665.42334576601, 57702.0, 57744.71127644335, 34082.48876266858, 59357.0, 59366.0, 59537.70231148817, 59726.0, 16101.838981610268, 16600.0, 16603.169841507875, 16648.75026874598, 16651.25069371881, 11017.173711971634, 11115.499802508906, 12065.09511786602, 12536.749265014649, 12688.17526860751, 18619.534511637317, 18625.578674772194, 18631.44105676832, 18638.627227455374, 18721.0, 6657.900131498031, 6746.865062698718, 6907.861842763217, 7134.678939447737, 7147.249538798455, 872.3108744092223, 939.6429356844492, 1004.7940172264668, 1065.5897479470304, 1078.0136175399577, 30364.392712145633, 31013.689863101532, 31231.84384078065, 31836.522452163237, 31857.362852742815, 1071.3625392891217, 1071.6570697376765, 1090.3948308688596, 1208.5951206345972, 1227.1410012990764, 15606.821260658247, 16572.834271657128, 16693.0, 16693.165341732863, 16886.324547018226, 483842.0, 487888.0, 488232.0, 488891.0, 492677.0, 54741.726291368286, 54862.17706734402, 54905.72547137239, 54920.45079549233, 54958.0, 1204.151073493185, 1242.2484396939722, 1286.3439645780531, 1316.4405127820637, 1332.5535945458187, 27479.587806182524, 31605.36789264248, 31831.84084079565, 32292.515612266303, 32533.511997320533, 49737.0, 50788.0, 51100.0, 54821.45178548323, 55480.72259638676, 459383.0, 460988.37645294337, 464785.0, 465072.0, 465450.0, 26845.194644160707, 27015.459690806467, 27326.180214593594, 28280.15159545217, 41547.7922610385, 44970.550294496636, 45192.774036130926, 45310.0, 45465.09069818651, 45835.54164458313, 37425.62574374222, 37662.81168594139, 38583.614163858, 39044.6095539041, 39765.0, 37658.0, 37698.81150594229, 38136.04659883525, 38656.42015369828, 39034.804825976826, 38662.613373865905, 39343.0, 39396.409053863645, 39645.80177099096, 39754.0, 32884.0, 34308.82845585756, 34664.0, 35184.472232916014, 35418.64581354154, 26475.205743827715, 26951.814996776902, 27003.0, 27065.0, 27220.0], "value": 19934.771566288546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.7319443403531, 384.7729839394758, 398.2274387687886, 401.43798681845414, 417.9024663177543, 12849.332610944188, 15284.312205950731, 16771.413000544984, 18897.527561810963, 19752.111154998023, 18493.630127397442, 18690.53273668159, 19078.42764717057, 19089.427317180507, 19421.89249720045, 5556.638818476807, 5664.668802831331, 5798.3331916829475, 5801.245838041049, 5802.97098514509, 4661.300804879262, 5798.942010579904, 5834.912476312858, 5839.708014599253, 5840.678762668052, 5646.378898321182, 5793.362730099686, 5794.71026448678, 5797.623154494965, 5801.158831969362, 5854.590178687494, 5987.251593550806, 5998.340182579913, 5999.610025348338, 6001.549883758714, 5657.57568182388, 5787.363390027093, 5812.070068788974, 5815.15680226369, 5836.445537673914, 12044.156909016372, 12068.336241506713, 12194.695132621666, 12197.08521860864, 13268.933655331759, 2189.4526368407896, 2230.5761905237955, 2316.9420764481024, 2552.710395303162, 2630.9605355919484, 9822.607095716163, 10038.447885366302, 10178.541965611565, 10190.082892539633, 10676.306040107369, 1885.3684015854567, 1916.434651777715, 1947.0751393088312, 1989.8308643765297, 1993.1614965535293, 79040.0, 80073.59963200205, 80890.59554702189, 81216.59391703062, 83320.0, 10395.428251446243, 10596.735081623021, 10605.628802991858, 10675.145988320915, 10924.0, 15132.621684457756, 15137.848621513864, 15140.318685659171, 15146.394144234107, 15152.772708409382, 7086.858262834818, 7308.415326773846, 7311.3785328247695, 7458.664360103809, 7510.549367037996, 7085.50401471892, 7086.893696594555, 7126.109236345457, 7408.629568521553, 7439.404847612178, 4474.865754027384, 4605.677602567788, 4607.390755639667, 4652.232381657056, 4733.431988161374, 1741.8519425848692, 1831.1851226204212, 1906.809319068082, 1939.33093082889, 1976.594798066393, 846.8687353460224, 917.6467060181744, 1015.94920253987, 1039.1634734039171, 1045.4459136657583, 18966.0, 19215.51961200981, 19556.315528956704, 19572.70640940387, 19930.20279188816, 956.8947415784259, 964.2189826240767, 968.9515524223901, 972.2902281334665, 981.1316984468797, 10434.0, 10854.131669466424, 10888.40113793749, 10936.890631093745, 10948.288361256493, 459340.0, 460704.0, 468045.0, 470103.0, 473171.0, 85707.5714621423, 87058.56470717606, 87322.69060447549, 89116.5544172275, 90731.54634226787, 927.5223260021095, 960.1550635440767, 968.6319198704447, 971.4948226921961, 976.7948730766476, 16599.253033613757, 16754.1622918856, 17020.0, 17308.22113004943, 17603.55991100233, 41369.37945930753, 42015.579844201166, 42179.1564168721, 42571.574284258, 43362.0, 56705.0, 57094.7145264271, 57231.42768572427, 58391.66433342619, 58428.707856460445, 7884.960575197317, 8046.114927358103, 8142.145074767064, 8181.386396020203, 8424.494530327955, 10126.493675316058, 12083.275003499819, 12312.445939932546, 12782.61652150437, 13024.739505209653, 10938.835917461403, 11002.064824489687, 11175.21773475849, 11274.323540587591, 11469.655910322703, 10553.472326383497, 10623.628173013907, 10756.946215268874, 10786.946065269623, 10823.296485728402, 11831.585894493652, 12011.219270747373, 12028.939855300667, 12085.456154472893, 12254.877451225373, 8181.100078991425, 8267.379946504156, 8397.118302578385, 8450.704225352083, 8694.695685651224, 10212.897871021194, 11412.885871141183, 11463.598774042868, 11489.368084755419, 12056.276623402624], "value": 8848.6742399817, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.9221242831643, 1467.9266036698166, 1566.45957144785, 1720.4773775208937, 1746.8515176210021, 8070.717524886615, 8369.121242269566, 8501.574921253943, 9132.726018219464, 9149.588268527916, 8608.698695545658, 8928.866067008983, 8934.106589341063, 9096.954515227431, 9103.908960910388, 2284.8057915077197, 2306.7116610423695, 2314.351981445198, 2315.988420057897, 2317.2584772872688, 1962.8822270663807, 2019.636465436221, 2253.7408198057187, 2302.1482051640915, 2335.486193037529, 2221.7556068832414, 2265.2864347730497, 2298.4139044543617, 2314.733805612351, 2319.0375993962484, 2300.3673989652893, 2306.308107567723, 2309.457277539777, 2358.7759162879497, 2368.348704106367, 2271.8523295985706, 2281.9429514262274, 2286.8503606609734, 2287.3938406322336, 2304.8156147508325, 8999.460032398078, 9116.453012819253, 9163.08369163085, 9179.265658747285, 9182.26541876655, 5141.794328226866, 5293.470652934676, 5293.523582877559, 5297.602679798992, 5297.761600727977, 3190.4257233697926, 3293.4236508610898, 3329.8002119872885, 3347.4811404232382, 3387.2548039431285, 1046.1108058150587, 1097.8746784545904, 1099.4887377369482, 1105.7180418993187, 1131.705756503307, 12659.746805063847, 12680.873191268154, 12713.42789574462, 12739.171953822972, 12742.108052436333, 4316.827326906951, 4338.869833904988, 4553.658475614276, 4588.036512332447, 4600.516945720651, 10789.352638841538, 10799.298045627009, 10799.352038877692, 10804.08165305942, 10810.189235807346, 5303.867403314871, 5313.415524292324, 5326.92009619856, 5327.493888080626, 5332.760025798849, 4673.696209746356, 4752.049590081998, 4798.66409351349, 4808.7595620219545, 4814.325994360793, 3978.5822488638855, 3992.640662340345, 4035.4955630546183, 4096.5903409658795, 4119.237940980928, 1109.2457129152253, 1146.4325159046325, 1185.3421351150023, 1280.9039322050883, 1288.761579107868, 838.4340570115148, 868.1492137705085, 868.6438560190292, 896.5965315608003, 970.1317320997667, 5726.828195154152, 5744.138379243147, 5770.163326317745, 5939.881202376015, 6244.937550624527, 926.0878035135386, 927.0821886332483, 927.2151109114927, 938.8497840345512, 968.2834702320232, 3952.5256969163884, 4207.8316867325575, 4218.0720241546815, 4224.598663126998, 4319.3952846601505, 11108.722281942855, 11331.376774277329, 11414.543418263342, 11451.198416110792, 11467.541298347973, 11366.602168924048, 11367.261128026652, 11371.943140284246, 11392.601258956063, 11398.373089479992, 7961.203879611992, 8264.504129752235, 8268.751937441886, 8411.327093832479, 8429.241368276884, 5745.454181852718, 6149.156777014366, 6223.688815559113, 6282.089097080806, 6365.554411191172, 9918.603255869684, 10090.94954525252, 10151.137153342048, 10168.949155254177, 10226.488675566043, 10820.350778953289, 10831.404272765074, 10865.402402867918, 10867.565297388015, 10873.673789786319, 8184.672613095409, 8186.54973976437, 8273.089960104504, 8274.627641756257, 8283.917160828314, 8693.087225841196, 8719.782005449915, 8794.868076978979, 8797.252233560219, 8798.47209167452, 7860.606969651609, 8027.1972802719265, 8027.799305017188, 8073.273405393543, 8237.299829514559, 7744.7676569703, 7802.453828231969, 7818.3354414875375, 7853.607319634109, 8161.183881611792, 6958.338957799001, 6958.721651133898, 7025.297470252934, 7029.683664235019, 7039.753608623673, 5058.418281897629, 5080.542751152414, 5121.103806833715, 5133.255677926754, 5352.839414817562, 5208.010478009166, 5274.736263186902, 5338.039152952506, 5338.306020217502, 5349.705766182898], "value": 4673.622802835158, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.63640722390926, 331.17537332043213, 335.92273777031284, 341.26627750336786, 342.28462513347097, 5280.762365693543, 5378.897326048151, 5380.623356365056, 5384.892302153955, 5428.294321738178, 5278.6568873023325, 5307.946920530793, 5352.705601191933, 5367.0, 5374.4894235047705, 3941.7634941903434, 4082.4284600156, 4111.897202569945, 4200.285951388271, 4211.115665710205, 4215.3255479123345, 4217.40956266123, 4235.915281694364, 4249.235137675218, 4255.808488618004, 4187.455630767996, 4187.602177793105, 4191.643710284629, 4217.114405974749, 4235.2800023996, 3917.4319723640056, 4049.959500404988, 4063.553009168989, 4138.793060347, 4207.558206388331, 3714.814259287025, 3729.1236559408517, 3729.3287208302495, 3736.663700266989, 3742.3076730804887, 5262.0, 5263.710390954241, 5274.26160337553, 5275.709835959021, 5280.970210808894, 4711.316859055418, 4747.382840230783, 4751.572358487718, 4777.092352453023, 4865.805367785284, 5578.386377498472, 5581.57669794204, 5584.944150558483, 5588.273524441837, 5606.495415412633, 4069.450624165759, 4080.3879418087527, 4088.8329185531716, 4090.6318431341024, 4098.426220329126, 5525.336959564837, 5778.826635200909, 5812.418758124154, 5823.737931793079, 5854.121881717776, 4062.979685101615, 4078.4290199372113, 4082.775447350424, 4106.630403263721, 4116.876493705194, 4139.19285739278, 4140.0, 4149.647279981175, 4150.771707556053, 4155.667546596265, 4531.909361812811, 4716.6698331116495, 4724.598409135193, 4803.639727020487, 4804.73573953429, 5587.413321601173, 5597.328320601554, 5603.299587551556, 5605.215269862222, 5609.5792815539, 1405.7118290750373, 1425.5224499792478, 1426.493594773861, 1441.8825410307095, 1444.9349779259958, 918.1461241045758, 927.784602171158, 935.822193783179, 936.513013233115, 975.8975307592742, 5196.298499702568, 5247.763850626731, 5309.442508536626, 5358.2498450217, 5478.753456094485, 5839.153322768175, 5849.239598852122, 5863.237779088777, 5866.882662346815, 5892.234009578728, 5225.947740522546, 5363.03465376236, 5458.972705136528, 5474.507294343449, 5491.533219676372, 2991.7307442330293, 3030.1515575638855, 3037.453258413507, 3056.006797790727, 3069.4474994501206, 5941.37615550361, 5951.1073338999495, 5971.954907891101, 5981.641101533922, 5985.521158307412, 5685.772569097273, 5799.217105690762, 5817.650940943557, 5839.766409343579, 5850.0, 5853.619514731528, 5856.297244330707, 5897.879402913432, 5908.497777688945, 5929.584929054925, 5507.697076660662, 5705.800296989585, 5772.7402266898935, 5783.566232532493, 5784.0, 5629.789595237044, 5643.294588176478, 5648.265725455664, 5652.745626446902, 5660.0, 5873.588848780544, 5891.351951285269, 5896.616719913192, 5917.644941303535, 5918.556108291981, 4084.509858816961, 4088.9591104088577, 4177.642266263538, 4200.201961627281, 4209.368594710818, 5620.803271885465, 5623.325200975745, 5660.0, 5673.432656734293, 5708.343540492897, 5494.118056003752, 5639.8590035249445, 5641.407652196624, 5658.830235092953, 5664.773409063591, 5479.671219726829, 5525.640833345819, 5532.170174473861, 5532.585056120887, 5546.334439867081, 5435.619506634655, 5473.589480788877, 5503.944960550343, 5514.373259888423, 5540.612157149122, 5364.624476286623, 5401.756920938488, 5491.890162196813, 5549.195366671729, 5632.464915832989, 5447.836564903059, 5450.454954504517, 5460.5904557159165, 5467.5625949923115, 5472.480114389126], "value": 4272.503361294398, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.4277185181484, 738.8263758016868, 769.5728870476883, 770.0989841884993, 802.9919700802992, 15523.844761552382, 16248.75626865598, 16385.508434746946, 16470.67058658826, 16484.835151648465, 15272.083674979482, 15351.92324038381, 15394.692106157898, 15398.769018464733, 15401.460948866792, 669.5480550628306, 675.3010633993817, 768.8154842837735, 779.1390513482605, 789.5578476053399, 804.4489524675587, 804.6258489802241, 806.891069705591, 807.5033854179667, 810.266708628689, 861.1044513705739, 862.6851199312268, 863.0377129500594, 863.1066845814584, 863.2662237098448, 706.9045678833395, 853.9274161696263, 857.6998050682274, 857.8069934264772, 857.8927634045745, 525.0417987173424, 603.7011679218815, 610.5115907274163, 613.9938600613981, 625.7278084033414, 14778.334974926154, 14860.0, 14888.925555372261, 14902.552923412315, 15065.246737663074, 13595.388207530685, 14193.361298741478, 14566.781498277534, 14651.069786042728, 14806.777898331533, 11837.289762614271, 11935.880641193564, 12108.273503589813, 12251.0, 12692.17500862441, 4227.788610569459, 4284.892877678052, 4322.632576231024, 4521.660875434355, 4524.86425407235, 75785.86321205186, 76345.61827190829, 78493.60753196255, 78593.60703196505, 80912.0, 11084.722881928017, 11263.324200547993, 11300.491477883515, 11440.256383335056, 11900.88099119015, 22192.778072219393, 22246.221382251544, 22252.332430027123, 22279.888600556893, 22357.329280121623, 8189.467684600482, 8239.176082391712, 8463.576821158917, 8524.531150786766, 8578.013528444184, 4394.648428125743, 4452.243118669834, 4463.66522510813, 4536.750478723636, 4538.773061346919, 3380.797152170878, 3498.160441494074, 3510.052285882797, 3512.5609298837644, 3517.103138699615, 555.9444055594489, 565.1889538512248, 566.8554518597814, 566.869619987397, 567.821136342051, 544.9209864569615, 644.3974883483958, 656.5896314803248, 663.5587334422555, 680.8876535371708, 8706.172913573328, 8972.147645973622, 9022.548872556346, 9135.680251191176, 9314.86027709585, 585.4262822434039, 602.6263716495713, 644.9226092868886, 665.9200895892524, 670.2794495916909, 4238.745675259494, 4419.18245124653, 4426.734395936254, 4531.093781243699, 4570.565796249351, 114142.0, 114641.0, 121179.0, 122011.0, 123816.38091809483, 59312.0, 82532.0, 96403.51798240964, 97314.51342743241, 97381.0, 64285.0, 92109.53945230231, 92827.0, 94311.52844235735, 94780.0, 8395.454295470774, 8608.182222689085, 8640.611172497127, 8646.351523635627, 8804.911950880409, 53519.0, 54168.45831541792, 54681.72659136679, 55392.72303638456, 55718.0, 71231.28768712247, 78612.0, 78731.0, 79822.20177798148, 79954.60022699849, 12099.818502722643, 12139.453724582238, 12312.87687123153, 12358.876411236131, 12358.938205308916, 12302.75394492123, 12597.370131493206, 12924.806127908278, 13501.864981350061, 13641.454341826216, 10768.030877221088, 11115.277506962022, 11219.0, 11296.49165787525, 11634.0, 10758.0, 11008.339499630049, 11016.779664406828, 11550.249233800112, 11665.183437159318, 10041.497925103862, 10175.135113515138, 10479.94760026195, 10635.734106647396, 10888.29226100301, 8681.435706679045, 8749.47503149813, 8914.153155450222, 9173.35786494939, 9409.106134917172, 8638.524881131598, 8839.779005524657, 8964.0, 9074.546272686472, 9098.909010909807], "value": 7199.139044618516, "unit": "score"}], "name": "dromaeo_css", "value": 7158.309588289311, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 110.0, 113.0, 112.0, 111.0, 110.0, 112.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 125.0, 159.0, 122.0, 125.0, 121.0, 125.0, 161.0, 124.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 160.0, 161.0, 163.0, 162.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 105.0, 76.0, 76.0, 75.0, 86.0, 98.0, 77.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 128.0, 82.0, 83.0, 81.0, 102.0, 82.0, 82.0, 122.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 145.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 52.0, 53.0, 54.0, 53.0, 54.0, 53.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 90.0, 84.0, 89.0, 84.0, 81.0, 80.0, 83.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 141.0, 138.0, 135.0, 144.0, 134.0, 138.0, 128.0, 171.0, 131.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 170.0, 169.0, 172.0, 172.0, 170.0, 171.0, 171.0, 168.0, 170.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 70.0, 65.0, 67.0, 64.0, 67.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1501.3999999999999, "alertThreshold": 2.0}]} 15:52:23 INFO - Return code: 0 15:52:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:52:24 INFO - # TBPL SUCCESS # 15:52:24 INFO - Running post-action listener: _resource_record_post_action 15:52:24 INFO - [mozharness: 2016-10-23 22:52:24.018001Z] Finished run-tests step (success) 15:52:24 INFO - Running post-run listener: _resource_record_post_run 15:52:24 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406323200; Read time: 180; Write time: 298208 15:52:24 INFO - TinderboxPrint: CPU usage
13.1% 15:52:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 180 15:52:24 INFO - TinderboxPrint: I/O write bytes / time
406,323,200 / 298,208 15:52:24 INFO - TinderboxPrint: CPU idle
8,308.8 (86.9%) 15:52:24 INFO - TinderboxPrint: CPU user
1,198.9 (12.5%) 15:52:24 INFO - TinderboxPrint: Swap in / out
0 / 0 15:52:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103624704; Read time: 0; Write time: 105652 15:52:24 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287531008; Read time: 180; Write time: 186488 15:52:24 INFO - Running post-run listener: _upload_blobber_files 15:52:24 INFO - Blob upload gear active. 15:52:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:52:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:52:25 INFO - (blobuploader) - INFO - Open directory for files ... 15:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:52:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:25 INFO - (blobuploader) - INFO - Done attempting. 15:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:52:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:29 INFO - (blobuploader) - INFO - Done attempting. 15:52:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:52:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:30 INFO - (blobuploader) - INFO - Done attempting. 15:52:30 INFO - (blobuploader) - INFO - Iteration through files over. 15:52:30 INFO - Return code: 0 15:52:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:52:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:52:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4a00d874b4c52f53c256712ec2912a8b2ca13e43804cb3fcc8a6fa239e99eb31a4934ca81a7b3d9f78cc1f9321dbb0b8a30a10c89edb9a9aeb6c6603028c583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/458b0d23b0c5cab444bfd991f866c74b9e569f37398ee035452d596d8e872957e0365d93726fe8f08cf0986b1e52b3de20a29203517f3dda0222052c58eefe33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9076ad2da256f5bf36ccd09f44976b9b5cbb8fba1bd94afb4220fcd2d47aeac489ea5ebddfadf136c8e09bc940c4e042c2adfbe40b8f104a61e33278b64f50bd"} 15:52:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:52:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:52:30 INFO - Contents: 15:52:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4a00d874b4c52f53c256712ec2912a8b2ca13e43804cb3fcc8a6fa239e99eb31a4934ca81a7b3d9f78cc1f9321dbb0b8a30a10c89edb9a9aeb6c6603028c583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/458b0d23b0c5cab444bfd991f866c74b9e569f37398ee035452d596d8e872957e0365d93726fe8f08cf0986b1e52b3de20a29203517f3dda0222052c58eefe33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9076ad2da256f5bf36ccd09f44976b9b5cbb8fba1bd94afb4220fcd2d47aeac489ea5ebddfadf136c8e09bc940c4e042c2adfbe40b8f104a61e33278b64f50bd"} 15:52:30 INFO - Running post-run listener: copy_logs_to_upload_dir 15:52:30 INFO - Copying logs to upload dir... 15:52:30 INFO - mkdir: /builds/slave/test/build/upload/logs 15:52:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.231078 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-10-23 15:52:29.770116) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 15:52:29.774720) ========= 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=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4a00d874b4c52f53c256712ec2912a8b2ca13e43804cb3fcc8a6fa239e99eb31a4934ca81a7b3d9f78cc1f9321dbb0b8a30a10c89edb9a9aeb6c6603028c583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/458b0d23b0c5cab444bfd991f866c74b9e569f37398ee035452d596d8e872957e0365d93726fe8f08cf0986b1e52b3de20a29203517f3dda0222052c58eefe33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9076ad2da256f5bf36ccd09f44976b9b5cbb8fba1bd94afb4220fcd2d47aeac489ea5ebddfadf136c8e09bc940c4e042c2adfbe40b8f104a61e33278b64f50bd"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010228 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1477251006/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4a00d874b4c52f53c256712ec2912a8b2ca13e43804cb3fcc8a6fa239e99eb31a4934ca81a7b3d9f78cc1f9321dbb0b8a30a10c89edb9a9aeb6c6603028c583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/458b0d23b0c5cab444bfd991f866c74b9e569f37398ee035452d596d8e872957e0365d93726fe8f08cf0986b1e52b3de20a29203517f3dda0222052c58eefe33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9076ad2da256f5bf36ccd09f44976b9b5cbb8fba1bd94afb4220fcd2d47aeac489ea5ebddfadf136c8e09bc940c4e042c2adfbe40b8f104a61e33278b64f50bd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 15:52:29.798802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:52:29.799116) ========= 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=920470bf432669ad7e3a5387000001ca-1477261910.409251-2132935676 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 15:52:29.882044) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 15:52:29.885381) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 15:52:29.885684) ========= ========= Total master_lag: 0.18 =========